Mar 28 01:42:01 living-room dbus-daemon[947]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.13" (uid=0 pid=1577 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=946 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 28 01:42:01 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:01.265+07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.114:43532 Mar 28 01:42:01 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:01.292+07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.114:43532 @ 0x2670bd0" latency=690.206341ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 28 01:42:01 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:01.292+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:43532 @ 0x2670bd0" latency=690.194749ms timeout=10s Mar 28 01:42:01 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:01.292+07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.114:43532 @ 0x2670bd0" Mar 28 01:42:02 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:02.366+07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.114:43532 @ 0x2670bd0" latency=689.318659ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 28 01:42:02 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:02.366+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:43532 @ 0x2670bd0" latency=688.808793ms timeout=10s Mar 28 01:42:02 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:02.366+07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.114:43532 @ 0x2670bd0" Mar 28 01:42:03 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:42:03 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:42:03 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:42:03 living-room bluealsa[1096]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_61_DE_71_C4_2D_9B, ...) Mar 28 01:42:11 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:11.293+07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:43532 @ 0x2670bd0" latency=690.194749ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 28 01:42:12 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:12.367+07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:43532 @ 0x2670bd0" latency=688.808793ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 28 01:42:12 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:12.747+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:43532 @ 0x2670bd0" latency=705.306975ms timeout=10s Mar 28 01:42:12 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:12.747+07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.114:43532 @ 0x2670bd0" Mar 28 01:42:12 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:12.747+07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.114:43532 @ 0x2670bd0" latency=706.303234ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 28 01:42:17 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:17.642+07:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 Mar 28 01:42:17 living-room dbus-daemon[947]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.13" (uid=0 pid=1577 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=946 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 28 01:42:18 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:18.182+07:00 level=INFO msg="new address was allocated" component=ble/conn old=4 new=5 Mar 28 01:42:22 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:22.748+07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:43532 @ 0x2670bd0" latency=705.306975ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 28 01:42:23 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:42:23 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:42:23 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:42:26 living-room volumio-remote-updater[971]: [2026-03-28 01:42:26] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Mar 28 01:42:31 living-room bluealsa[1096]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_67_4D_21_A7_B7_21, ...) Mar 28 01:42:31 living-room volumio-remote-updater[971]: [2026-03-28 01:42:31] [connect] Successful connection Mar 28 01:42:34 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:34.545+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:43532,00:00:00:00:00:00%04 @ 0x2670bd0" latency=699.495035ms timeout=10s Mar 28 01:42:34 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:34.545+07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.114:43532,00:00:00:00:00:00%04 @ 0x2670bd0" Mar 28 01:42:34 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:34.545+07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.114:43532,00:00:00:00:00:00%04 @ 0x2670bd0" latency=701.810427ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 28 01:42:37 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:37.724+07:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.1.114:43532 error="websocket: close 1006 (abnormal closure): unexpected EOF" Mar 28 01:42:37 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:37.724+07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.114:43532 Mar 28 01:42:37 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:37.724+07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.114:43532 Mar 28 01:42:38 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:38.769+07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.114:42616 Mar 28 01:42:38 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:38.789+07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.114:42616 @ 0x26702a0" latency=690.296025ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 28 01:42:38 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:38.791+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:42616 @ 0x26702a0" latency=691.492567ms timeout=10s Mar 28 01:42:38 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:38.791+07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.114:42616 @ 0x26702a0" Mar 28 01:42:38 living-room dbus-daemon[947]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.13" (uid=0 pid=1577 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=946 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 28 01:42:39 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:39.841+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:42616 @ 0x26702a0" latency=689.751892ms timeout=10s Mar 28 01:42:39 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:39.842+07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.114:42616 @ 0x26702a0" Mar 28 01:42:39 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:39.841+07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.114:42616 @ 0x26702a0" latency=689.756577ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 28 01:42:43 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:42:43 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:42:43 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:42:44 living-room volumio-remote-updater[971]: [2026-03-28 01:42:44] [connect] Successful connection Mar 28 01:42:44 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:44.545+07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:43532,00:00:00:00:00:00%04 @ 0x2670bd0" latency=699.495035ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 28 01:42:47 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:47.853+07:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=4 chunks=1 index=0 tries=11 Mar 28 01:42:47 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:47.854+07:00 level=ERROR msg="failed to send response" component=server dst="00:00:00:00:00:00%04 @ 0x2670bd0" id=439530328 status=STATUS_UNKNOWN error="peer is gone" Mar 28 01:42:48 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:48.791+07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:42616 @ 0x26702a0" latency=691.492567ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 28 01:42:49 living-room volumio5-onboarding[1577]: time=2026-03-28T01:42:49.842+07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.114:42616 @ 0x26702a0" latency=689.751892ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 28 01:42:59 living-room volumio-remote-updater[971]: [2026-03-28 01:42:59] [connect] Successful connection Mar 28 01:43:03 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:43:03 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:43:03 living-room volumio[2124]: libpng warning: iCCP: known incorrect sRGB profile Mar 28 01:43:14 living-room volumio-remote-updater[971]: [2026-03-28 01:43:14] [connect] Successful connection Mar 28 01:43:20 living-room volumio[1340]: --2026-03-28 01:41:03-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Mar 28 01:43:20 living-room volumio[1340]: Resolving github.com (github.com)... 20.205.243.166 Mar 28 01:43:20 living-room volumio[1340]: Connecting to github.com (github.com)|20.205.243.166|:443... connected. Mar 28 01:43:20 living-room volumio[1340]: HTTP request sent, awaiting response... 404 Not Found Mar 28 01:43:20 living-room volumio[1340]: 2026-03-28 01:41:04 ERROR 404: Not Found. Mar 28 01:43:20 living-room volumio[1340]: --2026-03-28 01:41:04-- http://a/ Mar 28 01:43:20 living-room volumio[1340]: Resolving a (a)... failed: Device or resource busy. Mar 28 01:43:20 living-room volumio[1340]: wget: unable to resolve host address ‘a’ Mar 28 01:43:20 living-room volumio[1340]: --2026-03-28 01:41:04-- http://packet.zip/ Mar 28 01:43:20 living-room volumio[1340]: Resolving packet.zip (packet.zip)... 162.255.119.167 Mar 28 01:43:20 living-room volumio[1340]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Mar 28 01:43:20 living-room volumio[1340]: HTTP request sent, awaiting response... 302 Found Mar 28 01:43:20 living-room volumio[1340]: Location: http://www.packet.zip/ [following] Mar 28 01:43:20 living-room volumio[1340]: --2026-03-28 01:41:04-- http://www.packet.zip/ Mar 28 01:43:20 living-room volumio[1340]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Mar 28 01:43:20 living-room volumio[1340]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Mar 28 01:43:20 living-room volumio[1340]: HTTP request sent, awaiting response... Read error (Connection reset by peer) in headers. Mar 28 01:43:20 living-room volumio[1340]: Retrying. Mar 28 01:43:20 living-room volumio[1340]: --2026-03-28 01:41:06-- (try: 2) http://www.packet.zip/ Mar 28 01:43:20 living-room volumio[1340]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... failed: Connection timed out. Mar 28 01:43:20 living-room volumio[1340]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Mar 28 01:43:20 living-room volumio[1340]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Mar 28 01:43:20 living-room volumio[1340]: HTTP request sent, awaiting response... 403 Forbidden Mar 28 01:43:20 living-room volumio[1340]: 2026-03-28 01:43:20 ERROR 403: Forbidden. Mar 28 01:43:20 living-room volumio[1340]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters Mar 28 01:43:20 living-room volumio[1340]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Mar 28 01:43:20 living-room volumio[1340]: /bin/rm: cannot remove 'a': No such file or directory Mar 28 01:43:20 living-room volumio[1340]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Mar 28 01:43:20 living-room volumio[1340]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 01:43:20 living-room volumio[1340]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Mar 28 01:43:20 living-room volumio[1340]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Mar 28 01:43:20 living-room volumio[1340]: /bin/rm: cannot remove 'a': No such file or directory Mar 28 01:43:20 living-room volumio[1340]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Mar 28 01:43:20 living-room volumio[1340]: at checkExecSyncError (node:child_process:887:11) Mar 28 01:43:20 living-room volumio[1340]: at execSync (node:child_process:959:15) Mar 28 01:43:20 living-room volumio[1340]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 Mar 28 01:43:20 living-room volumio[1340]: at new Promise () Mar 28 01:43:20 living-room volumio[1340]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) Mar 28 01:43:20 living-room volumio[1340]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Mar 28 01:43:20 living-room volumio[1340]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Mar 28 01:43:20 living-room volumio[1340]: at Socket.emit (node:events:514:28) Mar 28 01:43:20 living-room volumio[1340]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 28 01:43:20 living-room volumio[1340]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Mar 28 01:43:20 living-room volumio[1340]: status: 1, Mar 28 01:43:20 living-room volumio[1340]: signal: null, Mar 28 01:43:20 living-room volumio[1340]: output: [ Mar 28 01:43:20 living-room volumio[1340]: null, Mar 28 01:43:20 living-room volumio[1340]: , Mar 28 01:43:20 living-room volumio[1340]: Mar 28 01:43:20 living-room volumio[1340]: ], Mar 28 01:43:20 living-room volumio[1340]: pid: 2354, Mar 28 01:43:20 living-room volumio[1340]: stdout: , Mar 28 01:43:20 living-room volumio[1340]: stderr: Mar 28 01:43:20 living-room volumio[1340]: } Mar 28 01:43:20 living-room volumio[1340]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 01:43:20 living-room sudo[2370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 01:42' Mar 28 01:43:20 living-room sudo[2370]: 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"