Aug 30 17:08:09 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:08:09 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:08:09 volumiooo volumio[241289]: info: Discovery: Getting this device information Aug 30 17:08:09 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:09 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:09 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:08:09 volumiooo volumio[241289]: verbose: New Socket.io Connection to 192.168.68.68:3000 from 192.168.68.119 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 30 17:08:09 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 17:08:09 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 17:08:11 volumiooo rfkill[1100600]: unblock set for type bluetooth Aug 30 17:08:11 volumiooo bluealsa[940]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_7B_44_48_6B_D1_5F, ...) Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.621-04:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.119:63438 Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.810-04:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=191.233785ms platform=PLATFORM_IOS version=6.260807.0 Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.810-04:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.119:63438 @ 0xc0004a4870" latency=227.554628ms timeout=20s Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.817-04:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" Aug 30 17:08:17 volumiooo volumio[241289]: info: Received Get System Info Aug 30 17:08:17 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:08:17 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:08:17 volumiooo volumio[241289]: info: Discovery: Getting this device information Aug 30 17:08:17 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:17 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:17 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.821-04:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" name=Volumiooo Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.851-04:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" language=en Aug 30 17:08:17 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.854-04:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" timezone=America/New_York Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.854-04:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" available=false connected=false macAddress= ip4Address= ip6Address= Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.858-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" available=true connected=true macAddress=b4:d5:bd:6a:14:f4 ip4Address=192.168.68.68/22 ip6Address= ssid=Orbeez Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.858-04:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" setupComplete=true Aug 30 17:08:17 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 30 17:08:17 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:17.864-04:00 level=INFO msg="new address was allocated" component=ble/conn old=7 new=8 Aug 30 17:08:17 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 17:08:17 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 17:08:17 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 17:08:17 volumiooo volumio[241289]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 Aug 30 17:08:17 volumiooo volumio[241289]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 17:08:17 volumiooo volumio[241289]: {"cmd":"/usr/local/bin/alsacap -C 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 17:08:17 volumiooo volumio[241289]: amixer -c 1 info | grep "bytcht-nocodec" Aug 30 17:08:18 volumiooo volumio[241289]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 2 Aug 30 17:08:18 volumiooo volumio[241289]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 17:08:18 volumiooo volumio[241289]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 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)"} Aug 30 17:08:18 volumiooo volumio[241289]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 1 Aug 30 17:08:18 volumiooo volumio[241289]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 17:08:18 volumiooo volumio[241289]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 1\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 17:08:18 volumiooo volumio[241289]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Aug 30 17:08:18 volumiooo volumio[241289]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 17:08:18 volumiooo volumio[241289]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 17:08:18 volumiooo volumio[241289]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Aug 30 17:08:18 volumiooo volumio[241289]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 17:08:18 volumiooo volumio[241289]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 17:08:18 volumiooo volumio[241289]: amixer -c 5 info | grep "Expert Pro USB Audio 2.0" Aug 30 17:08:18 volumiooo volumio[241289]: Card sysdefault:5 'E20'/'DEVIALET Expert Pro USB Audio 2.0 at usb-0000:00:14.0-2, high speed' Aug 30 17:08:18 volumiooo volumio[241289]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Aug 30 17:08:18 volumiooo volumio[241289]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 17:08:18 volumiooo volumio[241289]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 17:08:18 volumiooo volumio[241289]: amixer -c 5 info | grep "Expert Pro USB Audio 2.0" Aug 30 17:08:18 volumiooo volumio[241289]: Card sysdefault:5 'E20'/'DEVIALET Expert Pro USB Audio 2.0 at usb-0000:00:14.0-2, high speed' Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.063-04:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" selectedOutputId=5 Aug 30 17:08:18 volumiooo volumio[241289]: info: Received Get System Info Aug 30 17:08:18 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:08:18 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:08:18 volumiooo volumio[241289]: info: Discovery: Getting this device information Aug 30 17:08:18 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:18 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:18 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.102-04:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" currentVersion=4.119 latestVersion=4.119 Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.103-04:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" status=UPDATE_STATUS_NONE progress=0 Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.107-04:00 level=INFO msg="emitting user changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" userId=Gj6I0gHKD5aWP38sfGHyNn0XGVr2 Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.108-04:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" providers=3 Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.108-04:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" plugins=53 Aug 30 17:08:18 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:18 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.112-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" state=STATUS_PAUSED positionMs=91168974 volume=100 Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.112-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.119:63438 @ 0xc0004a4870" id="mnt/USB/Crucial_X6/themusichub_2024-soca_2024-04-19_1936/2024/Remixes/Secret Society Remixes/Tack Back (Secret Society Remix).mp3" title="Tack Back (Secret Society Remix)" Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.137-04:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.739272ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 30 17:08:18 volumiooo dbus-daemon[834]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.27" (uid=0 pid=2451 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=908 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.264-04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.428-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=164.019917ms Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.464-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://securetoken.googleapis.com duration=199.427056ms Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.469-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=198.952711ms Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.490-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=219.426604ms Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.513-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=http://pushupdates.volumio.org duration=242.945505ms Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.528-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://www.googleapis.com duration=257.830865ms Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.531-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://google.com duration=266.339071ms Aug 30 17:08:18 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:18.655-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://database.volumio.cloud duration=384.933495ms Aug 30 17:08:19 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:19.632-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=1.367787113s Aug 30 17:08:19 volumiooo sudo[1100658]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 17:08:19 volumiooo sudo[1100658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:08:19 volumiooo sudo[1100660]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 17:08:19 volumiooo sudo[1100660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:08:19 volumiooo sudo[1100660]: pam_unix(sudo:session): session closed for user root Aug 30 17:08:19 volumiooo sudo[1100658]: pam_unix(sudo:session): session closed for user root Aug 30 17:08:19 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:19.890-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=http://plugins.volumio.org duration=1.619965206s Aug 30 17:08:19 volumiooo volumio[241289]: verbose: New Socket.io Connection to 192.168.68.68 from 192.168.68.119 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 30 17:08:19 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:19.978-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=http://cddb.volumio.org duration=1.707617999s Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:20 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 17:08:20 volumiooo volumio[241289]: info: Received Get System Info Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:08:20 volumiooo volumio[241289]: info: Discovery: Getting this device information Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:20 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:20 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:20 volumiooo volumio[241289]: info: Listing playlists Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 17:08:20 volumiooo sudo[1100670]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 17:08:20 volumiooo sudo[1100670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:08:20 volumiooo sudo[1100672]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 17:08:20 volumiooo sudo[1100672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 17:08:20 volumiooo sudo[1100672]: pam_unix(sudo:session): session closed for user root Aug 30 17:08:20 volumiooo sudo[1100670]: pam_unix(sudo:session): session closed for user root Aug 30 17:08:20 volumiooo volumio[241289]: verbose: New Socket.io Connection to 192.168.68.68 from 192.168.68.119 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:20 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 17:08:20 volumiooo volumio[241289]: info: Received Get System Info Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:08:20 volumiooo volumio[241289]: info: Discovery: Getting this device information Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:20 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:20 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:20 volumiooo volumio[241289]: info: Listing playlists Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 17:08:20 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 17:08:22 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:22.088-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://functions.volumio.cloud duration=3.817879387s Aug 30 17:08:22 volumiooo volumio5-onboarding[2451]: time=2026-08-30T17:08:22.088-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.119:63438 @ 0xc0004a4870" latency=44.103509ms timeout=10s endpoint=https://functions.volumio.cloud duration=3.818092586s Aug 30 17:08:22 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 17:08:22 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:08:22 volumiooo volumio[241289]: info: Received Get System Info Aug 30 17:08:22 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:08:22 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:08:22 volumiooo volumio[241289]: info: Discovery: Getting this device information Aug 30 17:08:22 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:22 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:22 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:08:22 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 17:08:30 volumiooo volumio[241289]: info: Received Get System Info Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:08:30 volumiooo volumio[241289]: info: Discovery: Getting this device information Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:30 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 17:08:30 volumiooo volumio[241289]: info: Retrieving Cloud Streaming UI Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 17:08:30 volumiooo volumio[241289]: info: Getting Tidal Cloud Configuration Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 17:08:30 volumiooo volumio[241289]: info: Getting Qobuz Cloud Configuration Aug 30 17:08:30 volumiooo volumio[241289]: info: Asking plugin for UI Config Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 17:08:30 volumiooo volumio[241289]: info: Getting Spotify Cloud Configuration Aug 30 17:08:30 volumiooo volumio[241289]: info: Asking plugin for UI Config Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 17:08:30 volumiooo volumio[241289]: info: Saving Spotify Acccount Aug 30 17:08:30 volumiooo volumio[241289]: info: Got Tidal Cloud Configuration Aug 30 17:08:30 volumiooo volumio[241289]: info: Got it Aug 30 17:08:30 volumiooo volumio[241289]: info: Got it Aug 30 17:08:30 volumiooo volumio[241289]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 17:08:30 volumiooo volumio[241289]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 17:08:30 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 30 17:08:34 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 30 17:08:34 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 30 17:08:34 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 17:08:38 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 17:08:38 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 17:08:39 volumiooo volumio[241289]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 17:08:39 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 17:08:39 volumiooo volumio[241289]: info: Received Get System Version Aug 30 17:08:39 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 17:08:39 volumiooo volumio[241289]: info: Received Get System Info Aug 30 17:08:39 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 17:08:39 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 17:08:39 volumiooo volumio[241289]: info: Discovery: Getting this device information Aug 30 17:08:39 volumiooo volumio[241289]: info: CoreCommandRouter::volumioGetState Aug 30 17:08:39 volumiooo volumio[241289]: info: CorePlayQueue::getTrack 18 Aug 30 17:08:39 volumiooo volumio[241289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 17:08:39 volumiooo volumio[241289]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 17:08:39 volumiooo volumio[241289]: Error: dns service error: unknown Aug 30 17:08:39 volumiooo volumio[241289]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Aug 30 17:08:39 volumiooo volumio[241289]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Aug 30 17:08:39 volumiooo volumio[241289]: errorCode: -65537 Aug 30 17:08:39 volumiooo volumio[241289]: } Aug 30 17:08:39 volumiooo volumio[241289]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 17:08:40 volumiooo sudo[1100749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 17:07' Aug 30 17:08:40 volumiooo sudo[1100749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"