Feb 09 15:08:00 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:00 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:05 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:05 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:10 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:10 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:15 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:15 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:20 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:20 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:25 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:25 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:30 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:30 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:35 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:35 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:40 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:40 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:45 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:45 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:50 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:50 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:08:53 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 09 15:08:53 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 09 15:08:53 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 09 15:08:55 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:08:55 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:00 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:00 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:05 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:05 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:10 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:10 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:15 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:15 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:20 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:20 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:20 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:20.957Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.39:53462 Feb 09 15:09:20 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:20.985Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.39:53462 @ 0x20b6240" latency=393.244903ms timeout=10s Feb 09 15:09:20 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:20.985Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" Feb 09 15:09:20 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:20.985Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.39:53462 @ 0x20b6240" latency=394.270254ms platform=PLATFORM_ANDROID version=5.260120.0 Feb 09 15:09:20 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:20.985Z level=INFO msg="updating device settings from app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.39:53462 @ 0x20b6240" latency=394.270254ms language=en timezone=Asia/Bangkok Feb 09 15:09:20 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:20 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:20 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:20 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:20 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:20 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:20 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:20 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 09 15:09:20 volumio volumio[1356]: info: Loading i18n strings for locale en Feb 09 15:09:20 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:20.990Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" name=Volumio Feb 09 15:09:20 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:20.990Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" language=en Feb 09 15:09:20 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 09 15:09:20 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:20.997Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" timezone=Asia/Bangkok Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.002Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" available=true connected=true macAddress=2c:cf:67:63:3c:dd ip4Address=192.168.1.50/24 ip6Address= Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.003Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.39:53560 @ 0x210a870" language=en Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.003Z level=ERROR msg="failed to send event" component=server dst="192.168.1.39:53560 @ 0x210a870" event=SERVER_EVENT_TYPE_DEVICE_LANGUAGE_CHANGED error="no WebSocket connection found for address: 192.168.1.39:53560" Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.003Z level=INFO msg="emitting device language changed event" component=server peer="00:00:00:00:00:00%22 @ 0x210b020" language=en Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.003Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.003Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" setupComplete=false Feb 09 15:09:21 volumio volumio[1356]: Updating browse sources language Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 15:09:21 volumio volumio[1356]: amixer -c 0 info | grep "vc4-hdmi-0" Feb 09 15:09:21 volumio volumio[1356]: Card sysdefault:0 'vc4hdmi0'/'vc4-hdmi-0' Feb 09 15:09:21 volumio volumio[1356]: amixer -c 0 info | grep "vc4-hdmi-0" Feb 09 15:09:21 volumio volumio[1356]: Card sysdefault:0 'vc4hdmi0'/'vc4-hdmi-0' Feb 09 15:09:21 volumio volumio[1356]: amixer -c 1 info | grep "vc4-hdmi-1" Feb 09 15:09:21 volumio volumio[1356]: Card sysdefault:1 'vc4hdmi1'/'vc4-hdmi-1' Feb 09 15:09:21 volumio volumio[1356]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Feb 09 15:09:21 volumio volumio[1356]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 09 15:09:21 volumio volumio[1356]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\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)"} Feb 09 15:09:21 volumio volumio[1356]: amixer -c 2 info | grep "I-Sabre Q2M DAC" Feb 09 15:09:21 volumio volumio[1356]: Card sysdefault:2 'DAC'/'I-Sabre Q2M DAC' Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.090Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" selectedOutputId=0 Feb 09 15:09:21 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:21 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:21 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.097Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" currentVersion=4.096 latestVersion=4.096 Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.098Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.39:53462 @ 0x20b6240" status=UPDATE_STATUS_NONE progress=0 Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.098Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" userId= Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.098Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" providers=3 Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.101Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" plugins=0 Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:21 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.102Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" state=STATUS_STOPPED positionMs=0 volume=100 Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.103Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" id= title= Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:21 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:21 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:21 volumio volumio[1356]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.39 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 09 15:09:21 volumio volumio[1356]: info: Fetching Streaming Services browse cache Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.799Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" language=en Feb 09 15:09:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.800Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" timezone=Asia/Bangkok Feb 09 15:09:21 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:21.800Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.39:53462 @ 0x20b6240" latency=392.120018ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTRO Feb 09 15:09:22 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/mcp/player0, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0001, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0001/char0002, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0001/char0004, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0001/char0006, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0001/char0008, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0014, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0014/char0015, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0014/char0017, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0014/char0019, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0029, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0029/desc002b, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char002c, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char002c/desc002e, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char002f, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char002f/desc0031, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0032, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0032/desc0034, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0035, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0035/desc0037, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0038, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0038/desc003a, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char003b, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char003b/desc003d, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char003e, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char003e/desc0040, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0041, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0043, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0043/desc0045, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0046, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0046/desc0048, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0049, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char0049/desc004b, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0028/char004c, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char005b, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char005b/desc005d, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char005e, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0060, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0060/desc0062, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0063, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0063/desc0065, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0066, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0066/desc0068, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0069, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char006b, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char006b/desc006d, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char006e, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char006e/desc0070, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0071, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0071/desc0073, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0074, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0076, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0076/desc0078, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0079, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char0079/desc007b, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char007c, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service005a/char007c/desc007e, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0082, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0082/char0083, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0090, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0090/char0091, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0093, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0093/char0094, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0093/char0094/desc0096, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0093/char0097, ...) Feb 09 15:09:23 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/service0093/char0097/desc0099, ...) Feb 09 15:09:23 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:23.290Z level=INFO msg="set device language" component=server type=REQUEST_TYPE_SET_DEVICE_LANGUAGE peer="192.168.1.39:53462 @ 0x20b6240" latency=391.403745ms timeout=10s language=th Feb 09 15:09:23 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 09 15:09:23 volumio volumio[1356]: info: Loading i18n strings for locale th Feb 09 15:09:23 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:23.311Z level=INFO msg="emitting device language changed event" component=server peer="00:00:00:00:00:00%22 @ 0x210b020" language=en Feb 09 15:09:23 volumio volumio[1356]: Updating browse sources language Feb 09 15:09:23 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 15:09:23 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:23.741Z level=INFO msg="new address was allocated" component=ble/conn old=35 new=36 Feb 09 15:09:23 volumio volumio[1356]: info: Fetching Streaming Services browse cache Feb 09 15:09:23 volumio dbus-daemon[1007]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.13" (uid=0 pid=1755 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.1" (uid=0 pid=1006 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Feb 09 15:09:24 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:24.310Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Feb 09 15:09:24 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:24.310Z level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%22 @ 0x210b020" event=SERVER_EVENT_TYPE_DEVICE_LANGUAGE_CHANGED error="peer is gone" Feb 09 15:09:24 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:24.310Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" language=en Feb 09 15:09:24 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 09 15:09:24 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 09 15:09:24 volumio volumio[1356]: info: Setting timezone to Asia/Bangkok Feb 09 15:09:24 volumio sudo[14408]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 09 15:09:24 volumio sudo[14408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:24 volumio sudo[14408]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:24 volumio sudo[14413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Bangkok /etc/localtime Feb 09 15:09:24 volumio sudo[14413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:24 volumio sudo[14413]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:24 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Feb 09 15:09:24 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Feb 09 15:09:24 volumio sudo[14420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 09 15:09:24 volumio sudo[14420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:24 volumio sudo[14420]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:24 volumio sudo[14424]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Feb 09 15:09:24 volumio sudo[14424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:24 volumio dbus-daemon[1007]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.785' (uid=0 pid=14425 comm="/usr/bin/timedatectl set-timezone Asia/Bangkok") Feb 09 15:09:24 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Feb 09 15:09:24 volumio dbus-daemon[1007]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 09 15:09:24 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Feb 09 15:09:24 volumio sudo[14424]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:24 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 09 15:09:24 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 09 15:09:24 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:24.535Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" timezone=Asia/Bangkok Feb 09 15:09:24 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:24.576Z level=INFO msg="emitting wifi scan event" component=server peer="192.168.1.39:53462 @ 0x20b6240" networks=27 Feb 09 15:09:25 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:25 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:25 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:25 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:25 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:25 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:25.076Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 09 15:09:25 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:25 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:25 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 09 15:09:25 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 09 15:09:25 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:25 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:25 volumio bluealsa[1132]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_6A_5B_95_1E_86_03/mcp/player0, ...) Feb 09 15:09:25 volumio dbus-daemon[1007]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.13" (uid=0 pid=1755 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.1" (uid=0 pid=1006 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F, ...) Feb 09 15:09:27 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:27.616Z level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Feb 09 15:09:27 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:27.616Z level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%22 @ 0x210b020" event=SERVER_EVENT_TYPE_DEVICE_LANGUAGE_CHANGED error="peer is gone" Feb 09 15:09:27 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:27.616Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" language=en Feb 09 15:09:27 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:27.720Z level=INFO msg="set device timezone" component=server type=REQUEST_TYPE_SET_DEVICE_TIMEZONE peer="192.168.1.39:53462 @ 0x20b6240" latency=394.229911ms timeout=10s timezone=Asia/Bangkok Feb 09 15:09:27 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 09 15:09:27 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 09 15:09:27 volumio volumio[1356]: info: Setting timezone to Asia/Bangkok Feb 09 15:09:27 volumio sudo[14445]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 09 15:09:27 volumio sudo[14445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:27 volumio sudo[14445]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:27 volumio sudo[14449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Bangkok /etc/localtime Feb 09 15:09:27 volumio sudo[14449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:27 volumio sudo[14449]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:27 volumio sudo[14453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 09 15:09:27 volumio sudo[14453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:27 volumio sudo[14453]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:27 volumio sudo[14457]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Feb 09 15:09:27 volumio sudo[14457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:27 volumio sudo[14457]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:27 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 09 15:09:27 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 09 15:09:27 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:27.850Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" timezone=Asia/Bangkok Feb 09 15:09:27 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:27 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:27 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:27 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:27 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:27 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:27 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:27 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:27.925Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" name=Volumio Feb 09 15:09:27 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:27.925Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.39:53462 @ 0x20b6240" latency=391.183278ms timeout=10s from=APP_PAGE_SETUP_V1_INTRO to=APP_PAGE_SETUP_V1_NAME Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/mcp/player0, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0001, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0001/char0002, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0001/char0004, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0001/char0006, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0001/char0008, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0014, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0014/char0015, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0014/char0017, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0014/char0019, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0029, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0029/desc002b, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char002c, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char002c/desc002e, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char002f, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char002f/desc0031, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0032, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0032/desc0034, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0035, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0035/desc0037, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0038, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0038/desc003a, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char003b, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char003b/desc003d, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char003e, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char003e/desc0040, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0041, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0043, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0043/desc0045, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0046, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0046/desc0048, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0049, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char0049/desc004b, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0028/char004c, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char005b, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char005b/desc005d, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char005e, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0060, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0060/desc0062, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0063, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0063/desc0065, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0066, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0066/desc0068, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0069, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char006b, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char006b/desc006d, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char006e, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char006e/desc0070, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0071, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0071/desc0073, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0074, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0076, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0076/desc0078, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0079, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char0079/desc007b, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char007c, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service005a/char007c/desc007e, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0082, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0082/char0083, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0090, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0090/char0091, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0093, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0093/char0094, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0093/char0094/desc0096, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0093/char0097, ...) Feb 09 15:09:27 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/service0093/char0097/desc0099, ...) Feb 09 15:09:28 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:28.579Z level=INFO msg="new address was allocated" component=ble/conn old=36 new=37 Feb 09 15:09:28 volumio dbus-daemon[1007]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.13" (uid=0 pid=1755 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.1" (uid=0 pid=1006 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Feb 09 15:09:29 volumio bluetoothd[1006]: src/device.c:new_auth() No agent available for request type 2 Feb 09 15:09:29 volumio bluetoothd[1006]: device_confirm_passkey: Operation not permitted Feb 09 15:09:29 volumio bluealsa[1132]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_71_F3_0A_41_14_1F/mcp/player0, ...) Feb 09 15:09:30 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:30 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:30 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:30.709Z level=INFO msg="set device name" component=server type=REQUEST_TYPE_SET_DEVICE_NAME peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=399.878842ms timeout=10s name=Volumio Feb 09 15:09:30 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 09 15:09:30 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:30 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:30 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:30 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:30 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:30 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:30 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:30 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:30.713Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" name=Volumio Feb 09 15:09:30 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:30.728Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" available=true connected=true macAddress=2c:cf:67:63:3c:dd ip4Address=192.168.1.50/24 ip6Address= Feb 09 15:09:30 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:30.729Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 09 15:09:30 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:30.729Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=399.058982ms timeout=10s from=APP_PAGE_SETUP_V1_NAME to=APP_PAGE_SETUP_V1_INTERNET Feb 09 15:09:30 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:30.747Z level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=391.058995ms timeout=1m0s Feb 09 15:09:31 volumio dbus-daemon[1007]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.13" (uid=0 pid=1755 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.1" (uid=0 pid=1006 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Feb 09 15:09:31 volumio bluetoothd[1006]: No matching connection for device Feb 09 15:09:32 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:32.484Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s Feb 09 15:09:32 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:32.737Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://www.googleapis.com duration=252.895442ms Feb 09 15:09:32 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:32.744Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=259.015922ms Feb 09 15:09:32 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:32.763Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=278.659251ms Feb 09 15:09:32 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:32.770Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=286.050049ms Feb 09 15:09:32 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:32.870Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://securetoken.googleapis.com duration=385.238717ms Feb 09 15:09:32 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:32.979Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=http://pushupdates.volumio.org duration=494.116781ms Feb 09 15:09:33 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:33.054Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=568.32931ms Feb 09 15:09:33 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:33.083Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://google.com duration=598.829538ms Feb 09 15:09:33 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:33.280Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://database.volumio.cloud duration=795.155351ms Feb 09 15:09:33 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:33.430Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://functions.volumio.cloud duration=945.049542ms Feb 09 15:09:33 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:33.483Z level=INFO msg="emitting wifi scan event" component=server peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" networks=33 Feb 09 15:09:33 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:33.500Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=http://plugins.volumio.org duration=1.01511793s Feb 09 15:09:33 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:33.646Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.161841093s Feb 09 15:09:33 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:33 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:33 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:33 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:33 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:33 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:33 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:33 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:33.987Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 09 15:09:33 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 09 15:09:33 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 09 15:09:34 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:34.034Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.169655ms timeout=10s endpoint=http://cddb.volumio.org duration=1.549667021s Feb 09 15:09:34 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:34 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:34 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:34 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:34 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:34 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:34 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:34 volumio volumio-remote-updater[1018]: Test mode disabled Feb 09 15:09:34 volumio volumio-remote-updater[1018]: Alpha mode disabled Feb 09 15:09:34 volumio volumio-remote-updater[1018]: Alpha legacy test mode disabled Feb 09 15:09:34 volumio volumio[1356]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 09 15:09:34 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 09 15:09:34 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:34.560Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" currentVersion=4.096 latestVersion=4.096 Feb 09 15:09:34 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:34.561Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" status=UPDATE_STATUS_NONE progress=0 Feb 09 15:09:34 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:34.561Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.117169ms timeout=10s from=APP_PAGE_SETUP_V1_INTERNET to=APP_PAGE_SETUP_V1_UPDATE Feb 09 15:09:35 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:35 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:35 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:35 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:35 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:35 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:35 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:35 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:35 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:35 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:35 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:35 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:35 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:35.442Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=392.476611ms timeout=10s from=APP_PAGE_SETUP_V1_UPDATE to=APP_PAGE_SETUP_V1_FINISH Feb 09 15:09:35 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70, ...) Feb 09 15:09:36 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:36.224Z level=INFO msg="reset device setup process" component=server type=REQUEST_TYPE_RESET_DEVICE_SETUP peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" latency=396.054145ms timeout=10s complete=true Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::Reload Ui Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Feb 09 15:09:36 volumio volumio[1356]: info: Wizard terminated Successfully Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::volumiosetStartupVolume Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:09:36 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:36.227Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" setupComplete=true Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 09 15:09:36 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:36 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:36 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:36.229Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.39:53462,00:00:00:00:00:00%24 @ 0x20b6240" setupComplete=true Feb 09 15:09:36 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 09 15:09:36 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 09 15:09:36 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:36.239Z level=INFO msg="disabling BLE discovery" Feb 09 15:09:36 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:36.242Z level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=2C:CF:67:63:3C:DF%00 Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/mcp/player0, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0001, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0001/char0002, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0001/char0004, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0001/char0006, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0001/char0008, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0014, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0014/char0015, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0014/char0017, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0014/char0019, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0029, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0029/desc002b, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char002c, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char002c/desc002e, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char002f, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char002f/desc0031, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0032, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0032/desc0034, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0035, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0035/desc0037, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0038, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0038/desc003a, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char003b, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char003b/desc003d, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char003e, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char003e/desc0040, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0041, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0043, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0043/desc0045, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0046, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0046/desc0048, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0049, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char0049/desc004b, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0028/char004c, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char005b, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char005b/desc005d, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char005e, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0060, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0060/desc0062, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0063, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0063/desc0065, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0066, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0066/desc0068, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0069, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char006b, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char006b/desc006d, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char006e, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char006e/desc0070, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0071, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0071/desc0073, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0074, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0076, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0076/desc0078, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0079, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char0079/desc007b, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char007c, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service005a/char007c/desc007e, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0082, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0082/char0083, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0090, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0090/char0091, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0093, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0093/char0094, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0093/char0094/desc0096, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0093/char0097, ...) Feb 09 15:09:36 volumio bluealsa[1132]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_54_BC_58_B2_89_70/service0093/char0097/desc0099, ...) Feb 09 15:09:37 volumio bluealsa[1132]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_54_BC_58_B2_89_70/mcp/player0, ...) Feb 09 15:09:40 volumio sudo[14499]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 09 15:09:40 volumio sudo[14499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:40 volumio sudo[14499]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:40 volumio sudo[14501]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 09 15:09:40 volumio sudo[14501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:40 volumio sudo[14501]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:40 volumio volumio[1356]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.39 UA: Mozilla/5.0 (Linux; Android 16; SM-S918B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 09 15:09:40 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:40 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:40 volumio sudo[14506]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 09 15:09:40 volumio sudo[14506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:40 volumio sudo[14506]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:40 volumio sudo[14508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 09 15:09:40 volumio sudo[14508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:40 volumio sudo[14508]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:40 volumio volumio[1356]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.39 UA: Mozilla/5.0 (Linux; Android 16; SM-S918B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::volumioGetVisibleSources Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:40 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 09 15:09:40 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:40 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:40 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:40 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:40 volumio volumio[1356]: info: Listing playlists Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 09 15:09:40 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 09 15:09:42 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 09 15:09:42 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 09 15:09:42 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:42 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:42 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:42 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:42 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:42 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:42 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:42 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 09 15:09:42 volumio volumio[1356]: info: MyVolumio login type: Token Feb 09 15:09:42 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 09 15:09:42 volumio volumio5-onboarding[1755]: time=2026-02-09T08:09:42.979Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.39:53462 @ 0x20b6240" userId= Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Feb 09 15:09:43 volumio volumio[1356]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 09 15:09:43 volumio sudo[14512]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 09 15:09:43 volumio sudo[14512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:43 volumio sudo[14512]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:43 volumio sudo[14514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 09 15:09:43 volumio sudo[14514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:43 volumio sudo[14514]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:43 volumio volumio[1356]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.39 UA: Mozilla/5.0 (Linux; Android 16; SM-S918B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 09 15:09:43 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:43 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:43 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:43 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::volumioGetVisibleSources Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:43 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 09 15:09:43 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:43 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:43 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:43 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:43 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:43 volumio volumio[1356]: info: Listing playlists Feb 09 15:09:44 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 09 15:09:44 volumio volumio[1356]: info: MyVolumio token set successfully Feb 09 15:09:44 volumio volumio[1356]: info: MYVOLUMIO: Adding device Feb 09 15:09:44 volumio volumio[1356]: info: MYVOLUMIO: Evaluating Server Feb 09 15:09:44 volumio volumio[1356]: info: MyVolumio Plan changed: premium Feb 09 15:09:44 volumio volumio[1356]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 09 15:09:44 volumio volumio[1356]: info: Removing browser output: myVolumio user plan is not superstar Feb 09 15:09:44 volumio volumio[1356]: info: Removing audio output: Feb 09 15:09:44 volumio volumio[1356]: info: MYVOLUMIO: Adding device Feb 09 15:09:44 volumio volumio[1356]: info: MYVOLUMIO: Evaluating Server Feb 09 15:09:44 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 09 15:09:44 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:44 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:44 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:44 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:44 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:44 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:44 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:44 volumio volumio[1356]: info: Remote config written successfully Feb 09 15:09:44 volumio volumio[1356]: info: Starting Tunnel 1 Feb 09 15:09:44 volumio volumio[1356]: info: Starting Tunnel Connection Checker Feb 09 15:09:44 volumio volumio[1356]: info: MYVolumio Device enabled Feb 09 15:09:44 volumio volumio[1356]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 09 15:09:44 volumio volumio[1356]: info: MyVolumio status changed Feb 09 15:09:44 volumio volumio[1356]: info: Streaming services startup Feb 09 15:09:44 volumio volumio[1356]: info: Starting Streaming Daemon Feb 09 15:09:44 volumio volumio[1356]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Feb 09 15:09:44 volumio sudo[14556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 09 15:09:44 volumio sudo[14556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:44 volumio sudo[14556]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:44 volumio volumio[1356]: error: Cannot start Volumio Streaming Daemon Feb 09 15:09:44 volumio volumio[1356]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 09 15:09:44 volumio volumio[1356]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 09 15:09:45 volumio sudo[14559]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 09 15:09:45 volumio sudo[14559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:45 volumio sudo[14559]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:45 volumio sudo[14561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 09 15:09:45 volumio sudo[14561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:45 volumio sudo[14561]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:45 volumio volumio[1356]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.39 UA: Mozilla/5.0 (Linux; Android 16; SM-S918B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 09 15:09:45 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:45 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:45 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:45 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::volumioGetVisibleSources Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:45 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 09 15:09:45 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:45 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:45 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:45 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:45 volumio volumio[1356]: info: Listing playlists Feb 09 15:09:45 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:45 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:45 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:45 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:45 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:45 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 09 15:09:45 volumio volumio[1356]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 09 15:09:45 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 09 15:09:46 volumio sudo[14581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 09 15:09:46 volumio sudo[14581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:46 volumio sudo[14581]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:46 volumio volumio[1356]: info: MRS: MultiRoom plugin initialized Feb 09 15:09:46 volumio volumio[1356]: info: MRS: STOPPING SNAPCLIENT Feb 09 15:09:46 volumio volumio[1356]: info: MRS: Snap server stop Feb 09 15:09:46 volumio sudo[14600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 09 15:09:46 volumio sudo[14600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:46 volumio volumio[1356]: info: MRS: STOPPING volumioStreaming Feb 09 15:09:46 volumio sudo[14603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 09 15:09:46 volumio sudo[14603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:46 volumio sudo[14606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 09 15:09:46 volumio sudo[14606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:46 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 09 15:09:46 volumio sudo[14609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 09 15:09:46 volumio sudo[14609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:09:46 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 09 15:09:46 volumio sudo[14609]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:46 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 09 15:09:46 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 09 15:09:46 volumio sudo[14600]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:46 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 09 15:09:46 volumio sudo[14603]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:46 volumio sudo[14606]: pam_unix(sudo:session): session closed for user root Feb 09 15:09:46 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 09 15:09:46 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 09 15:09:46 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 09 15:09:46 volumio volumio[1356]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 09 15:09:46 volumio volumio[1356]: info: Preparing to generate the ALSA configuration file Feb 09 15:09:46 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:46 volumio volumio[1356]: info: Discovery: Restarting Browsing Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 09 15:09:46 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:46 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:46 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 09 15:09:46 volumio volumio[1356]: info: Received Get System Info Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:09:46 volumio volumio[1356]: info: Discovery: Getting this device information Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Feb 09 15:09:46 volumio volumio[1356]: info: CorePlayQueue::getTrack 0 Feb 09 15:09:46 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:09:46 volumio volumio[1356]: info: MRS: Removed streaming files Feb 09 15:09:46 volumio volumio[1356]: info: MRS: volumioStreaming STOPPED Feb 09 15:09:46 volumio volumio[1356]: info: MRS: SNAPSERVER STOPPED Feb 09 15:09:46 volumio volumio[1356]: info: MRS: SNAPCLIENT STOPPED Feb 09 15:09:46 volumio dbus-daemon[1007]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Feb 09 15:09:46 volumio volumio[1356]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 15:09:46 volumio volumio[1356]: DBusError: The maximum number of active connections for UID 1000 has been reached Feb 09 15:09:46 volumio volumio[1356]: at _methodReturnHandlers. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:343:27) Feb 09 15:09:46 volumio volumio[1356]: at handleMessage (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:101:11) Feb 09 15:09:46 volumio volumio[1356]: at EventEmitter. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:151:9) Feb 09 15:09:46 volumio volumio[1356]: at EventEmitter.emit (node:events:514:28) Feb 09 15:09:46 volumio volumio[1356]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:132:14 Feb 09 15:09:46 volumio volumio[1356]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/message.js:65:9) Feb 09 15:09:46 volumio volumio[1356]: at Socket.emit (node:events:514:28) Feb 09 15:09:46 volumio volumio[1356]: at emitReadable_ (node:internal/streams/readable:609:12) Feb 09 15:09:46 volumio volumio[1356]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) { Feb 09 15:09:46 volumio volumio[1356]: type: 'org.freedesktop.DBus.Error.LimitsExceeded', Feb 09 15:09:46 volumio volumio[1356]: text: 'The maximum number of active connections for UID 1000 has been reached', Feb 09 15:09:46 volumio volumio[1356]: reply: Message { Feb 09 15:09:46 volumio volumio[1356]: type: 3, Feb 09 15:09:46 volumio volumio[1356]: _sent: false, Feb 09 15:09:46 volumio volumio[1356]: _serial: 1, Feb 09 15:09:46 volumio volumio[1356]: path: undefined, Feb 09 15:09:46 volumio volumio[1356]: interface: undefined, Feb 09 15:09:46 volumio volumio[1356]: member: undefined, Feb 09 15:09:46 volumio volumio[1356]: errorName: 'org.freedesktop.DBus.Error.LimitsExceeded', Feb 09 15:09:46 volumio volumio[1356]: replySerial: 1, Feb 09 15:09:46 volumio volumio[1356]: destination: ':not.active.yet', Feb 09 15:09:46 volumio volumio[1356]: sender: 'org.freedesktop.DBus', Feb 09 15:09:46 volumio volumio[1356]: signature: 's', Feb 09 15:09:46 volumio volumio[1356]: body: [ Feb 09 15:09:46 volumio volumio[1356]: 'The maximum number of active connections for UID 1000 has been reached' Feb 09 15:09:46 volumio volumio[1356]: ], Feb 09 15:09:46 volumio volumio[1356]: flags: 1 Feb 09 15:09:46 volumio volumio[1356]: } Feb 09 15:09:46 volumio volumio[1356]: } Feb 09 15:09:46 volumio volumio[1356]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 15:09:46 volumio sudo[14626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-09 15:08' Feb 09 15:09:46 volumio sudo[14626]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"