Mar 28 17:31:44 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:44.146+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.181:35166 Mar 28 17:31:44 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:44.956+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.181:35166 @ 0x1bc88d0" latency=-809.730974ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 28 17:31:44 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:44.957+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.181:35166 @ 0x1bc88d0" latency=-809.360974ms timeout=10s Mar 28 17:31:44 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:44.960+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" Mar 28 17:31:44 volumio2 volumio[1106]: info: Received Get System Info Mar 28 17:31:44 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 17:31:44 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 17:31:44 volumio2 volumio[1106]: info: Discovery: Getting this device information Mar 28 17:31:44 volumio2 volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 17:31:44 volumio2 volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 28 17:31:44 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 17:31:44 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 17:31:44 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.051+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" name=Volumio2 Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.065+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" language=en Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.108+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" timezone=Europe/Warsaw Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.111+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" available=true connected=false macAddress= ip4Address= ip6Address= Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.117+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" available=true connected=true macAddress=2c:cf:67:88:c2:08 ip4Address=192.168.1.196/24 ip6Address= ssid=NONAME Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.119+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" setupComplete=true Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 17:31:45 volumio2 volumio[1106]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 28 17:31:45 volumio2 volumio[1106]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 28 17:31:45 volumio2 volumio[1106]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 28 17:31:45 volumio2 volumio[1106]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.775+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" selectedOutputId=0 Mar 28 17:31:45 volumio2 volumio[1106]: info: Received Get System Info Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 17:31:45 volumio2 volumio[1106]: info: Discovery: Getting this device information Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 17:31:45 volumio2 volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.843+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" currentVersion=4.103 latestVersion=4.119 Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.846+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" status=UPDATE_STATUS_NONE progress=0 Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.850+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" userId=zA5al7RfIAMbkaTIaNPJWAlqs7Y2 Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.851+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" providers=9 Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.851+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" plugins=62 Mar 28 17:31:45 volumio2 volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 17:31:45 volumio2 volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.884+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.885+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.181:35166 @ 0x1bc88d0" id= title= Mar 28 17:31:45 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:45.935+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.181:35166 @ 0x1bc88d0" latency=-817.087745ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 28 17:31:47 volumio2 sudo[2253]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 17:31:47 volumio2 sudo[2253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 17:31:47 volumio2 sudo[2253]: pam_unix(sudo:session): session closed for user root Mar 28 17:31:47 volumio2 sudo[2251]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 17:31:47 volumio2 sudo[2251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 17:31:47 volumio2 sudo[2251]: pam_unix(sudo:session): session closed for user root Mar 28 17:31:47 volumio2 volumio[1106]: verbose: New Socket.io Connection to 192.168.1.196 from 192.168.1.181 UA: Mozilla/5.0 (Linux; Android 16; SM-A556B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 28 17:31:48 volumio2 sudo[2272]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 17:31:48 volumio2 sudo[2272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 17:31:48 volumio2 sudo[2272]: pam_unix(sudo:session): session closed for user root Mar 28 17:31:48 volumio2 sudo[2275]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 17:31:48 volumio2 sudo[2275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 17:31:48 volumio2 sudo[2275]: pam_unix(sudo:session): session closed for user root Mar 28 17:31:48 volumio2 volumio[1106]: verbose: New Socket.io Connection to 192.168.1.196 from 192.168.1.181 UA: Mozilla/5.0 (Linux; Android 16; SM-A556B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 17:31:49 volumio2 volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 17:31:49 volumio2 volumio[1106]: info: Received Get System Info Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 17:31:49 volumio2 volumio[1106]: info: Discovery: Getting this device information Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 17:31:49 volumio2 volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 17:31:49 volumio2 volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 17:31:49 volumio2 volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 28 17:31:50 volumio2 volumio[1106]: info: Listing playlists Mar 28 17:31:50 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 17:31:50 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 17:31:50 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 17:31:50 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 17:31:50 volumio2 volumio[1106]: info: Received Get System Info Mar 28 17:31:50 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 17:31:50 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 17:31:50 volumio2 volumio[1106]: info: Discovery: Getting this device information Mar 28 17:31:50 volumio2 volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 17:31:50 volumio2 volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 28 17:31:50 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 17:31:51 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 17:31:51 volumio2 volumio[1106]: info: Received Get System Info Mar 28 17:31:51 volumio2 bluealsa[953]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_52_EB_3F_A0_88_94, ...) Mar 28 17:31:51 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 17:31:51 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 17:31:51 volumio2 volumio[1106]: info: Discovery: Getting this device information Mar 28 17:31:51 volumio2 volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 17:31:51 volumio2 volumio[1106]: info: CorePlayQueue::getTrack 0 Mar 28 17:31:51 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 17:31:52 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 17:31:53 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 17:31:53 volumio2 volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 17:31:56 volumio2 volumio5-onboarding[2117]: time=2026-03-28T17:31:56.955+01:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Mar 28 17:31:57 volumio2 dbus-daemon[576]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.127" (uid=0 pid=2117 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.6" (uid=0 pid=870 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 28 17:31:57 volumio2 sudo[2281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Mar 28 17:31:57 volumio2 sudo[2281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 17:31:57 volumio2 sudo[2281]: pam_unix(sudo:session): session closed for user root Mar 28 17:31:57 volumio2 sudo[2283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Mar 28 17:31:57 volumio2 sudo[2283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 17:31:57 volumio2 volumio[1106]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Mar 28 17:31:57 volumio2 volumio[1106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 17:31:57 volumio2 sudo[2286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Mar 28 17:31:57 volumio2 sudo[2286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 17:31:57 volumio2 sudo[2287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Mar 28 17:31:57 volumio2 sudo[2287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 17:31:57 volumio2 volumio[1106]: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 17:31:57 volumio2 volumio[1106]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Mar 28 17:31:57 volumio2 volumio[1106]: at ChildProcess.exithandler (node:child_process:413:7) Mar 28 17:31:57 volumio2 volumio[1106]: at ChildProcess.emit (node:events:514:28) Mar 28 17:31:57 volumio2 volumio[1106]: at maybeClose (node:internal/child_process:1105:16) Mar 28 17:31:57 volumio2 volumio[1106]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Mar 28 17:31:57 volumio2 volumio[1106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 17:31:57 volumio2 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 28 17:31:57 volumio2 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 28 17:31:57 volumio2 sudo[2283]: pam_unix(sudo:session): session closed for user root Mar 28 17:31:57 volumio2 systemd[1]: Reloading. Mar 28 17:32:00 volumio2 sudo[2287]: pam_unix(sudo:session): session closed for user root Mar 28 17:32:00 volumio2 sudo[2286]: pam_unix(sudo:session): session closed for user root Mar 28 17:32:08 volumio2 sudo[2355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 17:31' Mar 28 17:32:09 volumio2 sudo[2355]: 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"