Aug 31 16:42:05 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 16:42:09 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 16:42:15 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 16:42:17 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 16:42:20 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:20.262-10:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s Aug 31 16:42:22 volumio volumio[1252]: verbose: New Socket.io Connection to 192.168.12.170 from 192.168.12.199 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Aug 31 16:42:23 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:23.358-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=http://pushupdates.volumio.org duration=3.093751229s Aug 31 16:42:23 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:23.358-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=3.093487279s Aug 31 16:42:23 volumio volumio[1252]: verbose: New Socket.io Connection to 192.168.12.170 from 192.168.12.199 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 31 16:42:23 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.017-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=3.754566125s Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.064-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://securetoken.googleapis.com duration=3.800662914s Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.081-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://www.googleapis.com duration=3.812817172s Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.104-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://functions.volumio.cloud duration=3.835539013s Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.105-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://functions.volumio.cloud duration=3.835850619s Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.108-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=3.844713663s Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.138-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=3.869428941s Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.140-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://database.volumio.cloud duration=3.871587164s Aug 31 16:42:24 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:24 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:24 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:24 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:24 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:24 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.665-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=http://plugins.volumio.org duration=4.39903088s Aug 31 16:42:24 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:24.932-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=http://cddb.volumio.org duration=4.664123951s Aug 31 16:42:24 volumio volumio[1252]: verbose: New Socket.io Connection to 192.168.12.170:3000 from 192.168.12.199 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 31 16:42:25 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 16:42:25 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 16:42:25 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:25.513-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:62267,00:00:00:00:00:00%03 @ 0x24002d0" latency=194.254053ms timeout=10s endpoint=https://google.com duration=5.249221592s Aug 31 16:42:27 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:27.278-10:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.12.199:62267 error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 31 16:42:27 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:27.279-10:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.12.199:62267 Aug 31 16:42:27 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:27.279-10:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.12.199:62267 Aug 31 16:42:27 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:27.279-10:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.12.199:62203 error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 31 16:42:27 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:27.280-10:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.12.199:62203 Aug 31 16:42:27 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:27.280-10:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.12.199:62203 Aug 31 16:42:27 volumio dbus-daemon[755]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.27" (uid=0 pid=2044 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=1029 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 31 16:42:30 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.714-10:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.12.199:64178 Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.867-10:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.12.199:64178 @ 0x29184e0" latency=176.515426ms platform=PLATFORM_IOS version=6.260807.0 Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.868-10:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.12.199:64178 @ 0x29184e0" latency=176.759924ms timeout=20s Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.869-10:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" Aug 31 16:42:31 volumio volumio[1252]: info: Received Get System Info Aug 31 16:42:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:31 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:31 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:31 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.876-10:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" name=Volumio Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.889-10:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" language=en Aug 31 16:42:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.893-10:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" timezone=Pacific/Honolulu Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.895-10:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" available=true connected=false macAddress= ip4Address= ip6Address= Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.900-10:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" available=true connected=true macAddress=b8:27:eb:30:e0:57 ip4Address=192.168.12.170/24 ip6Address= ssid=ThatRojasHR Aug 31 16:42:31 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:31.901-10:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" setupComplete=true Aug 31 16:42:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 16:42:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 16:42:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:42:31 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 16:42:31 volumio volumio[1252]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 31 16:42:32 volumio volumio[1252]: amixer -c 0 info | grep "bcm2835 Headphones" Aug 31 16:42:32 volumio volumio[1252]: Card sysdefault:0 'Headphones'/'bcm2835 Headphones' Aug 31 16:42:32 volumio volumio[1252]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 Aug 31 16:42:32 volumio volumio[1252]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 16:42:32 volumio volumio[1252]: {"cmd":"/usr/local/bin/alsacap -C 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1\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)"} Aug 31 16:42:32 volumio volumio[1252]: amixer -c 1 info | grep "vc4-hdmi" Aug 31 16:42:32 volumio volumio[1252]: Card sysdefault:1 'vc4hdmi'/'vc4-hdmi' Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.178-10:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" selectedOutputId=0 Aug 31 16:42:32 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 16:42:32 volumio volumio[1252]: info: Received Get System Info Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:32 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:32 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.449-10:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" currentVersion=4.119 latestVersion=4.119 Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.450-10:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.12.199:64178 @ 0x29184e0" status=UPDATE_STATUS_NONE progress=0 Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.451-10:00 level=INFO msg="emitting user changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" userId=ZicEghU6MOUqZgAgDM2w7jLwnP72 Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.452-10:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" providers=9 Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.454-10:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" plugins=71 Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:32 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.462-10:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" state=STATUS_STOPPED positionMs=0 volume=86 Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.463-10:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" id=http://api.somafm.com/folkfwd.pls title="SomaFM: Folk Forward" Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.630-10:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.12.199:64178 @ 0x29184e0" latency=177.028963ms platform=PLATFORM_IOS version=6.260807.0 Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.631-10:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.12.199:64178 @ 0x29184e0" latency=177.709882ms timeout=20s Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.631-10:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" Aug 31 16:42:32 volumio volumio[1252]: info: Received Get System Info Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:32 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:32 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.648-10:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" name=Volumio Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.650-10:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" language=en Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.655-10:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" timezone=Pacific/Honolulu Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.657-10:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" available=true connected=false macAddress= ip4Address= ip6Address= Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.663-10:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" available=true connected=true macAddress=b8:27:eb:30:e0:57 ip4Address=192.168.12.170/24 ip6Address= ssid=ThatRojasHR Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.664-10:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" setupComplete=true Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 16:42:32 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 16:42:32 volumio volumio[1252]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 31 16:42:32 volumio volumio[1252]: amixer -c 0 info | grep "bcm2835 Headphones" Aug 31 16:42:32 volumio volumio[1252]: Card sysdefault:0 'Headphones'/'bcm2835 Headphones' Aug 31 16:42:32 volumio volumio[1252]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 Aug 31 16:42:32 volumio volumio[1252]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 31 16:42:32 volumio volumio[1252]: {"cmd":"/usr/local/bin/alsacap -C 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1\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)"} Aug 31 16:42:32 volumio volumio[1252]: amixer -c 1 info | grep "vc4-hdmi" Aug 31 16:42:32 volumio volumio[1252]: Card sysdefault:1 'vc4hdmi'/'vc4-hdmi' Aug 31 16:42:32 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:32.909-10:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" selectedOutputId=0 Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:33 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:33 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:33 volumio volumio[1252]: info: Received Get System Info Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:33 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:33 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.156-10:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" currentVersion=4.119 latestVersion=4.119 Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.157-10:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.12.199:64178 @ 0x29184e0" status=UPDATE_STATUS_NONE progress=0 Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.157-10:00 level=INFO msg="emitting user changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" userId=ZicEghU6MOUqZgAgDM2w7jLwnP72 Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.157-10:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" providers=9 Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.158-10:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" plugins=71 Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:33 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.164-10:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" state=STATUS_STOPPED positionMs=0 volume=86 Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.165-10:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.12.199:64178 @ 0x29184e0" id=http://api.somafm.com/folkfwd.pls title="SomaFM: Folk Forward" Aug 31 16:42:33 volumio volumio[1252]: verbose: New Socket.io Connection to 192.168.12.170:3000 from 192.168.12.199 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.502-10:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.12.199:64178 @ 0x29184e0" latency=254.291838ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 16:42:33 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.571-10:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.702-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=129.764127ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.742-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://securetoken.googleapis.com duration=169.551343ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.763-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=190.417502ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.881-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=308.989534ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.906-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://database.volumio.cloud duration=331.015351ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.907-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://functions.volumio.cloud duration=333.395287ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.916-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://functions.volumio.cloud duration=342.832794ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.972-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://www.googleapis.com duration=398.356463ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.972-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=http://pushupdates.volumio.org duration=398.079595ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.978-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://google.com duration=404.899986ms Aug 31 16:42:33 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:33.979-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=406.706916ms Aug 31 16:42:34 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:34.108-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=http://cddb.volumio.org duration=535.287846ms Aug 31 16:42:34 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:34.471-10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.12.199:64178 @ 0x29184e0" latency=189.602803ms timeout=10s endpoint=http://plugins.volumio.org duration=896.357338ms Aug 31 16:42:36 volumio bluealsa[1107]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_57_1E_6A_57_0B_89, ...) Aug 31 16:42:36 volumio sudo[8388]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 16:42:36 volumio sudo[8386]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 16:42:36 volumio sudo[8386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:36 volumio sudo[8388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:36 volumio sudo[8388]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:36 volumio sudo[8386]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:36 volumio volumio[1252]: verbose: New Socket.io Connection to 192.168.12.170 from 192.168.12.199 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Aug 31 16:42:37 volumio sudo[8392]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 16:42:37 volumio sudo[8392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:37 volumio sudo[8392]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:37 volumio sudo[8394]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 16:42:37 volumio sudo[8394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:37 volumio sudo[8394]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:37 volumio volumio[1252]: verbose: New Socket.io Connection to 192.168.12.170 from 192.168.12.199 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:37 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 16:42:37 volumio volumio[1252]: info: Received Get System Info Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:37 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:37 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:37 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:37 volumio volumio[1252]: info: Listing playlists Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 16:42:37 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 16:42:39 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 16:42:39 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 16:42:39 volumio volumio[1252]: info: Received Get System Info Aug 31 16:42:39 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:39 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:39 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:39 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:39 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:39 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:40 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 16:42:40 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 16:42:40 volumio volumio[1252]: info: Received Get System Info Aug 31 16:42:40 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:40 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:40 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:40 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:40 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:40 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:41 volumio volumio5-onboarding[2044]: time=2026-08-31T16:42:41.911-10:00 level=INFO msg="new address was allocated" component=ble/conn old=4 new=5 Aug 31 16:42:42 volumio dbus-daemon[755]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.27" (uid=0 pid=2044 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=1029 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 31 16:42:42 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 16:42:46 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 16:42:46 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 16:42:47 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 16:42:50 volumio volumio[1252]: info: Enabling plugin touch_display Aug 31 16:42:50 volumio volumio[1252]: info: Loading plugin "touch_display"... Aug 31 16:42:50 volumio volumio[1252]: info: PLUGIN START: touch_display Aug 31 16:42:50 volumio volumio[1252]: info: Loading i18n strings for locale en Aug 31 16:42:50 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 16:42:50 volumio sudo[8418]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Aug 31 16:42:50 volumio sudo[8418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:50 volumio sudo[8418]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:50 volumio sudo[8421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 31 16:42:50 volumio volumio[1252]: info: touch_display: Backlight interface detected. Aug 31 16:42:50 volumio sudo[8421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:50 volumio sudo[8423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 31 16:42:50 volumio sudo[8423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:50 volumio sudo[8425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 31 16:42:50 volumio sudo[8425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:50 volumio systemd[1]: Stopping getty@tty1.service - Getty on tty1... Aug 31 16:42:51 volumio systemd[1]: getty@tty1.service: Deactivated successfully. Aug 31 16:42:51 volumio systemd[1]: Stopped getty@tty1.service - Getty on tty1. Aug 31 16:42:51 volumio volumio[1252]: info: touch_display: Raspberry Pi Foundation touch screen detected. Aug 31 16:42:51 volumio sudo[8434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Aug 31 16:42:51 volumio sudo[8434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:51 volumio sudo[8434]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:51 volumio volumio[1252]: info: touch_display: File permissions for backlight brightness control set. Aug 31 16:42:51 volumio sudo[8436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 31 16:42:51 volumio sudo[8436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:51 volumio sudo[8436]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:51 volumio volumio[1252]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 31 16:42:51 volumio systemd[1]: Reloading. Aug 31 16:42:51 volumio sudo[8421]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:51 volumio volumio[1252]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 31 16:42:51 volumio sudo[8431]: 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/ Aug 31 16:42:51 volumio sudo[8431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:51 volumio sudo[8431]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:51 volumio volumio[1252]: info: touch_display: IframeKeyboardBridge extension installed successfully Aug 31 16:42:53 volumio sudo[8423]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:53 volumio volumio[1252]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 31 16:42:54 volumio systemd[1]: Reloading. Aug 31 16:42:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 16:42:56 volumio volumio[1252]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 16:42:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 16:42:56 volumio volumio[1252]: info: Received Get System Version Aug 31 16:42:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 16:42:56 volumio volumio[1252]: info: Received Get System Info Aug 31 16:42:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 16:42:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 16:42:56 volumio volumio[1252]: info: Discovery: Getting this device information Aug 31 16:42:56 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:56 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 16:42:56 volumio sudo[8425]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:56 volumio volumio[1252]: info: touch_display: systemctl daemon-reload succeeded. Aug 31 16:42:56 volumio sudo[8491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 31 16:42:56 volumio sudo[8491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:57 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Aug 31 16:42:57 volumio sudo[8491]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:57 volumio volumio[1252]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 31 16:42:57 volumio volumio[1252]: info: touch_display: Volumio Kiosk started. Aug 31 16:42:57 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:57 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:57 volumio volumio[1252]: info: Done. Aug 31 16:42:57 volumio volumio[1252]: info: Enabling plugin touch_display Aug 31 16:42:57 volumio volumio[1252]: info: Loading plugin "touch_display"... Aug 31 16:42:57 volumio volumio[1252]: info: Applying required configuration parameters for plugin touch_display Aug 31 16:42:57 volumio volumio[1252]: info: PLUGIN START: touch_display Aug 31 16:42:57 volumio volumio[1252]: info: Loading i18n strings for locale en Aug 31 16:42:57 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 16:42:57 volumio startx[8515]: xauth: file /home/volumio/.Xauthority does not exist Aug 31 16:42:57 volumio sudo[8510]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Aug 31 16:42:57 volumio sudo[8510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:57 volumio sudo[8510]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:57 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Aug 31 16:42:57 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Aug 31 16:42:57 volumio sudo[8516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 31 16:42:57 volumio sudo[8516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:57 volumio sudo[8526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 31 16:42:57 volumio sudo[8526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:57 volumio sudo[8522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 31 16:42:57 volumio sudo[8522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:57 volumio volumio[1252]: info: touch_display: Backlight interface detected. Aug 31 16:42:57 volumio volumio[1252]: info: touch_display: X display number found: Aug 31 16:42:57 volumio startx[8527]: X.Org X Server 1.21.1.7 Aug 31 16:42:57 volumio startx[8527]: X Protocol Version 11, Revision 0 Aug 31 16:42:57 volumio startx[8527]: Current Operating System: Linux volumio 6.12.74-v7+ #1948 SMP Mon Mar 2 11:25:27 GMT 2026 armv7l Aug 31 16:42:57 volumio startx[8527]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Aug 31 16:42:57 volumio startx[8527]: xorg-server 2:21.1.7-3+rpt3+deb12u13 (https://www.debian.org/support) Aug 31 16:42:57 volumio startx[8527]: Current version of pixman: 0.44.0 Aug 31 16:42:57 volumio startx[8527]: Before reporting problems, check http://wiki.x.org Aug 31 16:42:57 volumio startx[8527]: to make sure that you have the latest version. Aug 31 16:42:57 volumio startx[8527]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 31 16:42:57 volumio startx[8527]: (++) from command line, (!!) notice, (II) informational, Aug 31 16:42:57 volumio startx[8527]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 31 16:42:57 volumio startx[8527]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Aug 31 16:42:57 2026 Aug 31 16:42:57 volumio startx[8527]: (==) Using config directory: "/etc/X11/xorg.conf.d" Aug 31 16:42:57 volumio startx[8527]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 31 16:42:57 volumio sudo[8535]: 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/ Aug 31 16:42:57 volumio sudo[8535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:57 volumio sudo[8535]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:57 volumio systemd[1]: Reloading. Aug 31 16:42:57 volumio volumio[1252]: info: touch_display: Raspberry Pi Foundation touch screen detected. Aug 31 16:42:57 volumio volumio[1252]: info: touch_display: IframeKeyboardBridge extension installed successfully Aug 31 16:42:57 volumio sudo[8540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 31 16:42:57 volumio sudo[8540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:57 volumio sudo[8540]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:57 volumio volumio[1252]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 31 16:42:57 volumio sudo[8538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Aug 31 16:42:57 volumio sudo[8538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:57 volumio sudo[8538]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:57 volumio volumio[1252]: info: touch_display: File permissions for backlight brightness control set. Aug 31 16:42:57 volumio bluealsa[1107]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_57_A0_5A_56_4E_09, ...) Aug 31 16:42:58 volumio sudo[8577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 31 16:42:58 volumio sudo[8577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:58 volumio sudo[8577]: pam_unix(sudo:session): session closed for user root Aug 31 16:42:58 volumio sudo[8579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Aug 31 16:42:58 volumio sudo[8579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:58 volumio volumio[1252]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 31 16:42:58 volumio sudo[8582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Aug 31 16:42:58 volumio sudo[8582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:58 volumio volumio[1252]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:42:58 volumio sudo[8585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Aug 31 16:42:58 volumio sudo[8585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 16:42:58 volumio volumio[1252]: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 16:42:58 volumio volumio[1252]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Aug 31 16:42:58 volumio volumio[1252]: at ChildProcess.exithandler (node:child_process:413:7) Aug 31 16:42:58 volumio volumio[1252]: at ChildProcess.emit (node:events:514:28) Aug 31 16:42:58 volumio volumio[1252]: at maybeClose (node:internal/child_process:1105:16) Aug 31 16:42:58 volumio volumio[1252]: at Socket. (node:internal/child_process:457:11) Aug 31 16:42:58 volumio volumio[1252]: at Socket.emit (node:events:514:28) Aug 31 16:42:58 volumio volumio[1252]: at Pipe. (node:net:337:12) Aug 31 16:42:58 volumio volumio[1252]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:43:00 volumio sudo[8625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 16:42' Aug 31 16:43:00 volumio sudo[8625]: 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"