Mar 27 07:27:02 volumio volumio[1436]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.151 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.4 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:02 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 07:27:02 volumio volumio[1436]: info: Received Get System Info Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:02 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:02 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:02 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:02 volumio volumio[1436]: info: Listing playlists Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 07:27:02 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 07:27:03 volumio volumio[1436]: info: Selecting previously unselected package libfile-basedir-perl. Mar 27 07:27:03 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:03.683Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:03 volumio volumio[1436]: info: Preparing to unpack .../17-libfile-basedir-perl_0.09-2_all.deb ... Mar 27 07:27:03 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:03.693Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:03 volumio volumio[1436]: info: Unpacking libfile-basedir-perl (0.09-2) ... Mar 27 07:27:03 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:03.708Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:03 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 07:27:04 volumio volumio[1436]: info: Received Get System Info Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:04 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:04 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 07:27:04 volumio volumio[1436]: info: Received Get System Info Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:04 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:04 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:04 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:05 volumio volumio[1436]: info: Selecting previously unselected package libfile-desktopentry-perl. Mar 27 07:27:05 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:05.181Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:05 volumio volumio[1436]: info: Preparing to unpack .../18-libfile-desktopentry-perl_0.22-3_all.deb ... Mar 27 07:27:05 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:05.197Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:05 volumio volumio[1436]: info: Unpacking libfile-desktopentry-perl (0.22-3) ... Mar 27 07:27:05 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:05.224Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:06 volumio volumio[1436]: info: Selecting previously unselected package libfile-mimeinfo-perl. Mar 27 07:27:06 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:06.656Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:06 volumio volumio[1436]: info: Preparing to unpack .../19-libfile-mimeinfo-perl_0.33-1_all.deb ... Mar 27 07:27:06 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:06.663Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:06 volumio volumio[1436]: info: Unpacking libfile-mimeinfo-perl (0.33-1) ... Mar 27 07:27:06 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:06.675Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:09 volumio volumio[1436]: info: Selecting previously unselected package libio-stringy-perl. Mar 27 07:27:09 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:09.533Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:09 volumio volumio[1436]: info: Preparing to unpack .../20-libio-stringy-perl_2.111-3_all.deb ... Mar 27 07:27:09 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:09.540Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.725Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.151:55462 Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.754Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.151:55462 @ 0x2c1c2d0" latency=26.024369ms timeout=10s Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.754Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.754Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.151:55462 @ 0x2c1c2d0" latency=26.046813ms platform=PLATFORM_IOS version=5.260209.0 Mar 27 07:27:12 volumio volumio[1436]: info: Received Get System Info Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:12 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:12 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.756Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" name=Volumio Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.758Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" language=en Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.759Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" timezone=Europe/London Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.760Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" available=true connected=true macAddress=2c:cf:67:3f:b3:dc ip4Address=192.168.1.137/24 ip6Address= Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.761Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.761Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" setupComplete=true Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 27 07:27:12 volumio volumio[1436]: amixer -c 0 info | grep "vc4-hdmi-0" Mar 27 07:27:12 volumio volumio[1436]: Card sysdefault:0 'vc4hdmi0'/'vc4-hdmi-0' Mar 27 07:27:12 volumio volumio[1436]: amixer -c 1 info | grep "vc4-hdmi-1" Mar 27 07:27:12 volumio volumio[1436]: Card sysdefault:1 'vc4hdmi1'/'vc4-hdmi-1' Mar 27 07:27:12 volumio volumio[1436]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 27 07:27:12 volumio volumio[1436]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 27 07:27:12 volumio volumio[1436]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 27 07:27:12 volumio volumio[1436]: amixer -c 5 info | grep "AudioQuest DragonFly Red v1.0" Mar 27 07:27:12 volumio volumio[1436]: Card sysdefault:5 'v10'/'AudioQuest AudioQuest DragonFly Red v1.0 at usb-xhci-hcd.1-1, full speed' Mar 27 07:27:12 volumio volumio[1436]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 27 07:27:12 volumio volumio[1436]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 27 07:27:12 volumio volumio[1436]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 27 07:27:12 volumio volumio[1436]: amixer -c 5 info | grep "AudioQuest DragonFly Red v1.0" Mar 27 07:27:12 volumio volumio[1436]: Card sysdefault:5 'v10'/'AudioQuest AudioQuest DragonFly Red v1.0 at usb-xhci-hcd.1-1, full speed' Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.830Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" selectedOutputId=5 Mar 27 07:27:12 volumio volumio[1436]: info: Received Get System Info Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:12 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:12 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.842Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" currentVersion=4.103 latestVersion=4.103 Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.842Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" status=UPDATE_STATUS_NONE progress=0 Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.842Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" userId= Mar 27 07:27:12 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:12.842Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" providers=3 Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 07:27:12 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 07:27:13 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:13.201Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" plugins=62 Mar 27 07:27:13 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:13 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:13 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:13.203Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" state=STATUS_STOPPED positionMs=0 volume=41 Mar 27 07:27:13 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:13.203Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.151:55462 @ 0x2c1c2d0" id="mnt/USB/FA14-0D7F/Mick's Music/AC_DC/AC_DC - 1994 - If You Want Blood Youve Got It/01. Riff Raff.flac" title="Riff Raff" Mar 27 07:27:13 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:13 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:13 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:13 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:13 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:13 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:13 volumio volumio[1436]: verbose: New Socket.io Connection to 192.168.1.137:3000 from 192.168.1.151 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Mar 27 07:27:13 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 07:27:13 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 07:27:14 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:14.046Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.151:55462 @ 0x2c1c2d0" latency=27.269396ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 27 07:27:14 volumio sudo[7516]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 07:27:14 volumio sudo[7516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:14 volumio sudo[7518]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 07:27:14 volumio sudo[7516]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:14 volumio sudo[7518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:14 volumio sudo[7518]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:14 volumio volumio[1436]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.151 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 07:27:14 volumio sudo[7536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 07:27:14 volumio sudo[7536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:14 volumio sudo[7536]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:14 volumio sudo[7538]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 07:27:14 volumio sudo[7538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:14 volumio sudo[7538]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 07:27:14 volumio volumio[1436]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.151 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:14 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 07:27:14 volumio volumio[1436]: info: Received Get System Info Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:14 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:14 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:14 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:14 volumio volumio[1436]: info: Listing playlists Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 07:27:14 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 07:27:16 volumio bluealsa[1196]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_65_97_CD_B7_66_F8, ...) Mar 27 07:27:16 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 27 07:27:16 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 07:27:16 volumio volumio[1436]: info: Received Get System Info Mar 27 07:27:16 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:16 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:16 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:16 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:16 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:16 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:17 volumio volumio[1436]: info: Unpacking libio-stringy-perl (2.111-3) ... Mar 27 07:27:17 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:17.674Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:17 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:17.688Z level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Mar 27 07:27:17 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 07:27:17 volumio volumio[1436]: info: Received Get System Info Mar 27 07:27:17 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:17 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:17 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:17 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:17 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:17 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:18 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:18 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:20 volumio volumio[1436]: info: Selecting previously unselected package libxml-parser-perl. Mar 27 07:27:20 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:20.599Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:20 volumio volumio[1436]: info: Preparing to unpack .../21-libxml-parser-perl_2.46-4_armhf.deb ... Mar 27 07:27:20 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:20.608Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:20 volumio volumio[1436]: info: Unpacking libxml-parser-perl (2.46-4) ... Mar 27 07:27:20 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:20.618Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:23 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:23.988Z level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Mar 27 07:27:24 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:24 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:24 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 27 07:27:24 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 27 07:27:25 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:25.878Z level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 Mar 27 07:27:26 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:26 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:28 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:28.038Z level=INFO msg="new address was allocated" component=ble/conn old=4 new=5 Mar 27 07:27:28 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:28 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:28 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 27 07:27:29 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:29.898Z level=INFO msg="new address was allocated" component=ble/conn old=5 new=6 Mar 27 07:27:30 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:30 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:30 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Mar 27 07:27:31 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:31.788Z level=INFO msg="new address was allocated" component=ble/conn old=6 new=7 Mar 27 07:27:31 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:32 volumio dbus-daemon[1080]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.17" (uid=0 pid=1735 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=1079 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 27 07:27:33 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 07:27:33 volumio volumio[1436]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 27 07:27:33 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 27 07:27:33 volumio volumio[1436]: info: Received Get System Version Mar 27 07:27:33 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 07:27:33 volumio volumio[1436]: info: Received Get System Info Mar 27 07:27:33 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 07:27:33 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 07:27:33 volumio volumio[1436]: info: Discovery: Getting this device information Mar 27 07:27:33 volumio volumio[1436]: info: CoreCommandRouter::volumioGetState Mar 27 07:27:33 volumio volumio[1436]: info: CorePlayQueue::getTrack 0 Mar 27 07:27:33 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 07:27:34 volumio volumio[1436]: info: Selecting previously unselected package libxml-twig-perl. Mar 27 07:27:34 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:34.386Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:34 volumio volumio[1436]: info: Preparing to unpack .../22-libxml-twig-perl_1%3a3.52-2_all.deb ... Mar 27 07:27:34 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:34.394Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:34 volumio volumio[1436]: info: Unpacking libxml-twig-perl (1:3.52-2) ... Mar 27 07:27:34 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:34.405Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:35 volumio volumio[1436]: info: Selecting previously unselected package libnet-dbus-perl. Mar 27 07:27:35 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:35.871Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:35 volumio volumio[1436]: info: Preparing to unpack .../23-libnet-dbus-perl_1.2.0-2_armhf.deb ... Mar 27 07:27:35 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:35.879Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:35 volumio volumio[1436]: info: Unpacking libnet-dbus-perl (1.2.0-2) ... Mar 27 07:27:35 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:35.894Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:45 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 07:27:45 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 07:27:45 volumio volumio[1436]: info: Selecting previously unselected package libtie-ixhash-perl. Mar 27 07:27:45 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:45.871Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:45 volumio volumio[1436]: info: Preparing to unpack .../24-libtie-ixhash-perl_1.23-4_all.deb ... Mar 27 07:27:45 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:45.881Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:45 volumio volumio[1436]: info: Unpacking libtie-ixhash-perl (1.23-4) ... Mar 27 07:27:45 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:45.891Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:46 volumio volumio[1436]: info: Selecting previously unselected package libupower-glib3:armhf. Mar 27 07:27:46 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:46.021Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:46 volumio volumio[1436]: info: Preparing to unpack .../25-libupower-glib3_0.99.20-2_armhf.deb ... Mar 27 07:27:46 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:46.031Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:46 volumio volumio[1436]: info: Unpacking libupower-glib3:armhf (0.99.20-2) ... Mar 27 07:27:46 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:46.064Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:46 volumio volumio[1436]: info: Selecting previously unselected package libwidevinecdm0. Mar 27 07:27:46 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:46.445Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:46 volumio volumio[1436]: info: Preparing to unpack .../26-libwidevinecdm0_4.10.2662.3+1_armhf.deb ... Mar 27 07:27:46 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:46.453Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:46 volumio volumio[1436]: info: Unpacking libwidevinecdm0 (4.10.2662.3+1) ... Mar 27 07:27:46 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:46.470Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:48 volumio volumio[1436]: info: Enabling plugin touch_display Mar 27 07:27:48 volumio volumio[1436]: info: Loading plugin "touch_display"... Mar 27 07:27:49 volumio volumio[1436]: info: PLUGIN START: touch_display Mar 27 07:27:49 volumio volumio[1436]: info: Loading i18n strings for locale en Mar 27 07:27:49 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 07:27:49 volumio sudo[7667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 27 07:27:49 volumio sudo[7667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:49 volumio sudo[7669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 27 07:27:49 volumio sudo[7669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:49 volumio volumio[1436]: info: touch_display: Backlight interface detected. Mar 27 07:27:49 volumio sudo[7672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 27 07:27:49 volumio sudo[7672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:49 volumio volumio[1436]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 27 07:27:49 volumio volumio[1436]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 27 07:27:49 volumio sudo[7676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/11-0045/brightness Mar 27 07:27:49 volumio sudo[7676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:49 volumio sudo[7678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 27 07:27:49 volumio sudo[7678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:49 volumio sudo[7676]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:49 volumio sudo[7678]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:49 volumio volumio[1436]: info: touch_display: File permissions for backlight brightness control set. Mar 27 07:27:49 volumio volumio[1436]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 27 07:27:49 volumio volumio[1436]: error: touch_display: Error setting display brightness: Error: EINVAL: invalid argument, write Mar 27 07:27:55 volumio volumio[1436]: info: touch_display: New settings written to /etc/X11/xorg.conf.d/99-vc4.conf. Mar 27 07:27:55 volumio systemd[1]: Reloading. Mar 27 07:27:55 volumio volumio[1436]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 07:27:55 volumio sudo[7672]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:55 volumio systemd[1]: Reloading. Mar 27 07:27:55 volumio volumio[1436]: info: touch_display: systemctl daemon-reload succeeded. Mar 27 07:27:55 volumio sudo[7720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 27 07:27:55 volumio sudo[7720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:27:56 volumio sudo[7669]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:56 volumio volumio[1436]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 27 07:27:56 volumio sudo[7720]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:56 volumio volumio[1436]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Mar 27 07:27:56 volumio volumio[1436]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Mar 27 07:27:56 volumio systemd[1]: Stopping getty@tty1.service - Getty on tty1... Mar 27 07:27:56 volumio volumio[1436]: info: Error: Error Mar 27 07:27:56 volumio systemd[1]: getty@tty1.service: Deactivated successfully. Mar 27 07:27:56 volumio systemd[1]: Stopped getty@tty1.service - Getty on tty1. Mar 27 07:27:56 volumio volumio[1436]: info: touch_display: About to detect HDMI ports... Mar 27 07:27:56 volumio volumio[1436]: info: touch_display: Detected HDMI ports: HDMI 0 (HDMI-A-1), HDMI 1 (HDMI-A-2) Mar 27 07:27:56 volumio sudo[7667]: pam_unix(sudo:session): session closed for user root Mar 27 07:27:56 volumio volumio[1436]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 27 07:27:57 volumio volumio[1436]: info: Selecting previously unselected package libx11-protocol-perl. Mar 27 07:27:57 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:57.383Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:57 volumio volumio[1436]: info: Preparing to unpack .../27-libx11-protocol-perl_0.56-9_all.deb ... Mar 27 07:27:57 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:57.394Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:57 volumio volumio[1436]: info: Unpacking libx11-protocol-perl (0.56-9) ... Mar 27 07:27:57 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:57.413Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:57 volumio volumio[1436]: info: Selecting previously unselected package libxml-xpathengine-perl. Mar 27 07:27:57 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:57.801Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:57 volumio volumio[1436]: info: Preparing to unpack .../28-libxml-xpathengine-perl_0.14-2_all.deb ... Mar 27 07:27:57 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:57.810Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:57 volumio volumio[1436]: info: Unpacking libxml-xpathengine-perl (0.14-2) ... Mar 27 07:27:57 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:57.822Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:58 volumio volumio[1436]: info: Selecting previously unselected package notification-daemon. Mar 27 07:27:58 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:58.079Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:58 volumio volumio[1436]: info: Preparing to unpack .../29-notification-daemon_3.20.0-4_armhf.deb ... Mar 27 07:27:58 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:58.089Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:58 volumio volumio[1436]: info: Unpacking notification-daemon (3.20.0-4) ... Mar 27 07:27:58 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:58.113Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:58 volumio volumio[1436]: info: Selecting previously unselected package upower. Mar 27 07:27:58 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:58.277Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:58 volumio volumio[1436]: info: Preparing to unpack .../30-upower_0.99.20-2_armhf.deb ... Mar 27 07:27:58 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:58.289Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:59 volumio volumio[1436]: info: Unpacking upower (0.99.20-2) ... Mar 27 07:27:59 volumio volumio5-onboarding[1735]: time=2026-03-27T07:27:59.618Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:27:59 volumio dbus-daemon[1080]: [system] Reloaded configuration Mar 27 07:27:59 volumio volumio5-onboarding[1735]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio shairport-sync[1785]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio dbus-daemon[1080]: [system] Reloaded configuration Mar 27 07:27:59 volumio volumio5-onboarding[1735]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio shairport-sync[1785]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio dbus-daemon[1080]: [system] Reloaded configuration Mar 27 07:27:59 volumio shairport-sync[1785]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio volumio5-onboarding[1735]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:27:59 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio dbus-daemon[1080]: [system] Reloaded configuration Mar 27 07:28:02 volumio shairport-sync[1785]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio volumio5-onboarding[1735]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio dbus-daemon[1080]: [system] Reloaded configuration Mar 27 07:28:02 volumio shairport-sync[1785]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio volumio5-onboarding[1735]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio dbus-daemon[1080]: [system] Reloaded configuration Mar 27 07:28:02 volumio volumio5-onboarding[1735]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio shairport-sync[1785]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:02 volumio volumio[1436]: info: Setting up libxml-parser-perl (2.46-4) ... Mar 27 07:28:02 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:02.605Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:03 volumio volumio[1436]: info: Setting up libdouble-conversion3:armhf (3.2.1-1) ... Mar 27 07:28:03 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:03.964Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libtie-ixhash-perl (1.23-4) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.004Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up chromium-codecs-ffmpeg-extra (126.0.6478.164-rpt1) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.039Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libio-stringy-perl (2.111-3) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.077Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libxml-twig-perl (1:3.52-2) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.122Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libnet-dbus-perl (1.2.0-2) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.165Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up chromium-sandbox (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.206Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up notification-daemon (3.20.0-4) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.260Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libxnvctrl0:armhf (525.85.05-3~deb12u1) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.308Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libnspr4:armhf (2:4.35-1) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.351Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libipc-system-simple-perl (1.30-2) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.394Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libxml-xpathengine-perl (0.14-2) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.434Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libopenh264-7:armhf (2.3.1+dfsg-3+deb12u2) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.475Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up fonts-liberation (1:1.07.4-11) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.518Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libupower-glib3:armhf (0.99.20-2) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.582Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libharfbuzz-subset0:armhf (6.0.0+dfsg-3) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.609Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up zenoty (0.2) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.649Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up xdg-utils (1.1.3-4.1) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.689Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: update-alternatives: using /usr/bin/xdg-open to provide /usr/bin/open (open) in auto mode Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.725Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libx11-protocol-perl (0.56-9) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.750Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up libfile-basedir-perl (0.09-2) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.789Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:04 volumio volumio[1436]: info: Setting up upower (0.99.20-2) ... Mar 27 07:28:04 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:04.827Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:05 volumio systemd[1]: Reloading. Mar 27 07:28:05 volumio volumio[1436]: info: upower.service is a disabled or a static unit, not starting it. Mar 27 07:28:05 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:05.617Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:05 volumio volumio[1436]: info: Setting up libnss3:armhf (2:3.87.1-1+deb12u2) ... Mar 27 07:28:05 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:05.633Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:05 volumio volumio[1436]: info: Setting up libwidevinecdm0 (4.10.2662.3+1) ... Mar 27 07:28:05 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:05.675Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:05 volumio volumio[1436]: info: Setting up libfile-desktopentry-perl (0.22-3) ... Mar 27 07:28:05 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:05.736Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: Setting up chromium-common (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.225Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: Setting up chromium (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.266Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: update-alternatives: using /usr/bin/chromium to provide /usr/bin/x-www-browser (x-www-browser) in auto mode Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.421Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: update-alternatives: using /usr/bin/chromium to provide /usr/bin/gnome-www-browser (gnome-www-browser) in auto mode Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.432Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: Setting up libfile-mimeinfo-perl (0.33-1) ... Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.461Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: Setting up rpi-chromium-mods (20250506) ... Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.525Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: Setting up chromium-l10n (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.590Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: Setting up chromium-browser (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.637Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: Processing triggers for hicolor-icon-theme (0.17-2) ... Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.680Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:07 volumio volumio[1436]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Mar 27 07:28:07 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:07.753Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:10 volumio volumio[1436]: info: Processing triggers for man-db (2.11.2-2) ... Mar 27 07:28:10 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:10.516Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:10 volumio volumio[1436]: info: Processing triggers for dbus (1.14.10-1~deb12u1) ... Mar 27 07:28:10 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:10.732Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:10 volumio dbus-daemon[1080]: [system] Reloaded configuration Mar 27 07:28:10 volumio volumio5-onboarding[1735]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:10 volumio shairport-sync[1785]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:10 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:10 volumio volumio[1436]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Mar 27 07:28:10 volumio volumio[1436]: info: Processing triggers for udev (252.39-1~deb12u1+rpi1) ... Mar 27 07:28:10 volumio volumio5-onboarding[1735]: time=2026-03-27T07:28:10.779Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 07:28:15 volumio volumio[1436]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Mar 27 07:28:15 volumio volumio[1436]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Mar 27 07:28:15 volumio sudo[7878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Mar 27 07:28:15 volumio sudo[7878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 07:28:15 volumio sudo[7878]: pam_unix(sudo:session): session closed for user root Mar 27 07:28:15 volumio volumio[1436]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Mar 27 07:28:15 volumio volumio[1436]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 07:28:15 volumio volumio[1436]: TypeError: Cannot read properties of undefined (reading 'split') Mar 27 07:28:15 volumio volumio[1436]: at /data/plugins/user_interface/touch_display/index.js:1274:37 Mar 27 07:28:15 volumio volumio[1436]: at ChildProcess.exithandler (node:child_process:413:7) Mar 27 07:28:15 volumio volumio[1436]: at ChildProcess.emit (node:events:514:28) Mar 27 07:28:15 volumio volumio[1436]: at maybeClose (node:internal/child_process:1105:16) Mar 27 07:28:15 volumio volumio[1436]: at Socket. (node:internal/child_process:457:11) Mar 27 07:28:15 volumio volumio[1436]: at Socket.emit (node:events:514:28) Mar 27 07:28:15 volumio volumio[1436]: at Pipe. (node:net:337:12) Mar 27 07:28:15 volumio volumio[1436]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 07:28:15 volumio sudo[7897]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 07:27' Mar 27 07:28:15 volumio sudo[7897]: 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"