Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.393+10:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.10.164:55322 Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.436+10:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.10.164:55322 @ 0x1bd2090" latency=13.926212ms platform=PLATFORM_ANDROID version=6.260915.0 Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.438+10:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.10.164:55322 @ 0x1bd2090" latency=11.832072ms timeout=20s Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.438+10:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" Oct 02 17:41:06 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:41:06 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:41:06 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.444+10:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" name=Bedroom Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.445+10:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" language=en Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.447+10:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" timezone=Australia/Melbourne Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.448+10:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" available=true connected=false macAddress= ip4Address= ip6Address= Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.453+10:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" available=true connected=true macAddress=e4:5f:01:53:53:99 ip4Address=192.168.10.172/24 ip6Address= ssid=Mesh.matts-domain Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.453+10:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" setupComplete=true Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 17:41:06 bedroom volumio[1244]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 02 17:41:06 bedroom volumio[1244]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 02 17:41:06 bedroom volumio[1244]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 02 17:41:06 bedroom volumio[1244]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Oct 02 17:41:06 bedroom volumio[1244]: amixer -c 2 info | grep "vc4-hdmi-0" Oct 02 17:41:06 bedroom volumio[1244]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Oct 02 17:41:06 bedroom volumio[1244]: amixer -c 3 info | grep "vc4-hdmi-1" Oct 02 17:41:06 bedroom volumio[1244]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.570+10:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" selectedOutputId=0 Oct 02 17:41:06 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:41:06 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:41:06 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.595+10:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" currentVersion=4.119 latestVersion=4.119 Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.596+10:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" status=UPDATE_STATUS_NONE progress=0 Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.596+10:00 level=INFO msg="emitting user changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" userId=CFQurIfSsSSyIXm0sbi50k1aSxq1 Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.596+10:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" providers=9 Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.596+10:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" plugins=74 Oct 02 17:41:06 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:41:06 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.599+10:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" state=STATUS_STOPPED positionMs=0 volume=86 Oct 02 17:41:06 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:06.600+10:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.164:55322 @ 0x1bd2090" id= title= Oct 02 17:41:08 bedroom bluealsa[943]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_52_77_83_39_FB_08, ...) Oct 02 17:41:10 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:41:10.714+10:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Oct 02 17:41:10 bedroom dbus-daemon[765]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.35" (uid=0 pid=2231 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.3" (uid=0 pid=799 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Oct 02 17:42:02 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:02.991+10:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.10.230:43880 Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.051+10:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.10.230:43880 @ 0x1801aa0" latency=438.907303ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.051+10:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.10.230:43880 @ 0x1801aa0" latency=438.9592ms timeout=20s Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.051+10:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" Oct 02 17:42:03 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:42:03 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:03 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.055+10:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" name=Bedroom Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.056+10:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" language=en Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.058+10:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" timezone=Australia/Melbourne Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.059+10:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" available=true connected=false macAddress= ip4Address= ip6Address= Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.062+10:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" available=true connected=true macAddress=e4:5f:01:53:53:99 ip4Address=192.168.10.172/24 ip6Address= ssid=Mesh.matts-domain Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.062+10:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" setupComplete=true Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 17:42:03 bedroom volumio[1244]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 02 17:42:03 bedroom volumio[1244]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 02 17:42:03 bedroom volumio[1244]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 02 17:42:03 bedroom volumio[1244]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Oct 02 17:42:03 bedroom volumio[1244]: amixer -c 2 info | grep "vc4-hdmi-0" Oct 02 17:42:03 bedroom volumio[1244]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Oct 02 17:42:03 bedroom volumio[1244]: amixer -c 3 info | grep "vc4-hdmi-1" Oct 02 17:42:03 bedroom volumio[1244]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.179+10:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" selectedOutputId=0 Oct 02 17:42:03 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:42:03 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:03 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.190+10:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" currentVersion=4.119 latestVersion=4.119 Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.190+10:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" status=UPDATE_STATUS_NONE progress=0 Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.190+10:00 level=INFO msg="emitting user changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" userId=CFQurIfSsSSyIXm0sbi50k1aSxq1 Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.191+10:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" providers=9 Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.191+10:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" plugins=74 Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:03 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.194+10:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" state=STATUS_STOPPED positionMs=0 volume=86 Oct 02 17:42:03 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:03.194+10:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.230:43880 @ 0x1801aa0" id= title= Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:42:03 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:03 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:42:03 bedroom volumio[1244]: verbose: New Socket.io Connection to 192.168.10.172:3000 from 192.168.10.230 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 17:42:03 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 17:42:09 bedroom bluealsa[943]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_7B_91_04_3A_59_3F, ...) Oct 02 17:42:11 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:11.069+10:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 Oct 02 17:42:11 bedroom dbus-daemon[765]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.35" (uid=0 pid=2231 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.3" (uid=0 pid=799 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Oct 02 17:42:13 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:13.726+10:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=447.191984ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 02 17:42:13 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:13.747+10:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s Oct 02 17:42:13 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:13.998+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=251.748129ms Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.000+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=252.184088ms Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.069+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://www.googleapis.com duration=321.885203ms Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.075+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=328.160641ms Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.237+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://securetoken.googleapis.com duration=490.193501ms Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.270+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=523.440694ms Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.413+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=http://pushupdates.volumio.org duration=664.961828ms Oct 02 17:42:14 bedroom sudo[9703]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 17:42:14 bedroom sudo[9703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:14 bedroom sudo[9703]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:14 bedroom sudo[9705]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 17:42:14 bedroom sudo[9705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:14 bedroom sudo[9705]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:14 bedroom volumio[1244]: verbose: New Socket.io Connection to 192.168.10.172 from 192.168.10.230 UA: Mozilla/5.0 (Linux; Android 15; SM-F926B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.617+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://database.volumio.cloud duration=869.671141ms Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.644+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://google.com duration=896.988911ms Oct 02 17:42:14 bedroom sudo[9710]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 17:42:14 bedroom sudo[9710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:14 bedroom sudo[9710]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:14 bedroom sudo[9712]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 17:42:14 bedroom sudo[9712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:14 bedroom sudo[9712]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.825+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.076837263s Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.828+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.080063917s Oct 02 17:42:14 bedroom volumio[1244]: verbose: New Socket.io Connection to 192.168.10.172 from 192.168.10.230 UA: Mozilla/5.0 (Linux; Android 15; SM-F926B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 02 17:42:14 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 17:42:14 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:14.990+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=http://plugins.volumio.org duration=1.242263663s Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:15 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 17:42:15 bedroom volumio[1244]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 02 17:42:15 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:42:15 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:15 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:15 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:15 bedroom volumio[1244]: info: Listing playlists Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 17:42:15 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 17:42:15 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:15.398+10:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.230:43880,00:00:00:00:00:00%03 @ 0x1801aa0" latency=438.02386ms timeout=10s endpoint=http://cddb.volumio.org duration=1.650177434s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 17:42:16 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 02 17:42:16 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 17:42:16 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:42:16 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:42:16 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:42:16 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:42:16 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:16 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:16 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:42:16 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 02 17:42:18 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 17:42:18 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:42:18 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:42:18 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:42:18 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:42:18 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:18 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:18 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:42:20 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 17:42:20 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:42:20 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:42:20 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:42:20 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:42:20 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:20 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:20 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:42:21 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 17:42:21 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:42:21 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:42:21 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:42:21 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:42:21 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:21 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:21 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 17:42:24 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 02 17:42:33 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 17:42:33 bedroom volumio[1244]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 02 17:42:33 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 02 17:42:33 bedroom volumio[1244]: info: Received Get System Version Oct 02 17:42:33 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 17:42:33 bedroom volumio[1244]: info: Received Get System Info Oct 02 17:42:33 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:42:33 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:42:33 bedroom volumio[1244]: info: Discovery: Getting this device information Oct 02 17:42:33 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:33 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:33 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:42:35 bedroom volumio[1244]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 02 17:42:35 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 02 17:42:35 bedroom volumio[1244]: info: Preparing to save Alsa Options, stopping services first Oct 02 17:42:35 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:35 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:35 bedroom volumio[1244]: info: CoreCommandRouter::volumioPause Oct 02 17:42:35 bedroom volumio[1244]: info: CoreStateMachine::pause Oct 02 17:42:35 bedroom volumio[1244]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} Oct 02 17:42:35 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 17:42:35 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 02 17:42:35 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:35 bedroom volumio[1244]: info: Enabling I2S DAC: HiFiBerry Digi+ Pro Oct 02 17:42:35 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Oct 02 17:42:35 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:35 bedroom sudo[9761]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Oct 02 17:42:35 bedroom sudo[9761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom sudo[9761]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom volumio[1244]: info: No Overlays Loaded Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:36 bedroom sudo[9765]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-digi-pro Oct 02 17:42:36 bedroom sudo[9765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom volumio[1244]: warn: Unable to locate the device HDMI Out a reboot may be required. Oct 02 17:42:36 bedroom kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Oct 02 17:42:36 bedroom kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Oct 02 17:42:36 bedroom kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Oct 02 17:42:36 bedroom kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Oct 02 17:42:36 bedroom kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Oct 02 17:42:36 bedroom kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock44-gpio Oct 02 17:42:36 bedroom kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock48-gpio Oct 02 17:42:36 bedroom sudo[9765]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: Device 2 does not have any Mixer Control Available Oct 02 17:42:36 bedroom volumio[1244]: info: QobuzConnect: setDeactiveState invoked Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:36 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:36 bedroom vtcs[2509]: [2026-10-02 17:42:36.082] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 02 17:42:36 bedroom vtcs[2509]: [2026-10-02 17:42:36.082] [tisoc] [error] [SpkconServer.cpp:476] socket write error. fd=3 errorno=32 error=Broken pipe Oct 02 17:42:36 bedroom vtcs[2509]: [2026-10-02 17:42:36.082] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 02 17:42:36 bedroom sudo[9796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 17:42:36 bedroom sudo[9796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 02 17:42:36 bedroom volumio[1244]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 02 17:42:36 bedroom systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 02 17:42:36 bedroom volumio[1244]: info: Disabling external Volume Control Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 17:42:36 bedroom systemd[1]: vtcs.service: Killing process 2514 (vtcs) with signal SIGKILL. Oct 02 17:42:36 bedroom systemd[1]: vtcs.service: Deactivated successfully. Oct 02 17:42:36 bedroom systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 02 17:42:36 bedroom systemd[1]: vtcs.service: Consumed 1.417s CPU time. Oct 02 17:42:36 bedroom kernel: wm8804 1-003b: revision E Oct 02 17:42:36 bedroom sudo[9796]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 02 17:42:36 bedroom volumio[1244]: info: CoreStateMachine::pushState Oct 02 17:42:36 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 02 17:42:36 bedroom volumio[1244]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:42:36 bedroom (udev-worker)[9780]: controlC4: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 4' failed with exit code 99. Oct 02 17:42:36 bedroom volumio[1244]: info: MRS: Pushing multiroomSync output Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:36 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:36 bedroom volumio5-onboarding[2231]: time=2026-10-02T17:42:36.214+10:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x18a9260" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 17:42:36 bedroom volumio[1244]: info: Preparing to generate the ALSA configuration file Oct 02 17:42:36 bedroom sudo[9815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 17:42:36 bedroom sudo[9815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom volumio[1244]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 17:42:36 bedroom volumio[1244]: info: Reading ALSA contributions from plugins. Oct 02 17:42:36 bedroom sudo[9822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 17:42:36 bedroom sudo[9822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom volumio[1244]: info: I2S Param [object Object] successfully enabled Oct 02 17:42:36 bedroom sudo[9815]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom sudo[9822]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 17:42:36 bedroom volumio[1244]: info: Getting Alsa Cards List without I2S DAC Oct 02 17:42:36 bedroom sudo[9829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 17:42:36 bedroom sudo[9829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:36 bedroom systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 17:42:36 bedroom qobuz-connect[2423]: 20261002 17:42:36.426 [2423.2423] INFO SampleApp: Stopping Local configuration server Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 17:42:36 bedroom volumio[1244]: info: Asound.conf file written Oct 02 17:42:36 bedroom sudo[9842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 17:42:36 bedroom sudo[9842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom sudo[9842]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 17:42:36 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 02 17:42:36 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 02 17:42:36 bedroom volumio[1244]: No state is present for card vc4hdmi0 Oct 02 17:42:36 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 02 17:42:36 bedroom volumio[1244]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 02 17:42:36 bedroom volumio[1244]: Hardware is initialized using a generic method Oct 02 17:42:36 bedroom volumio[1244]: No state is present for card vc4hdmi0 Oct 02 17:42:36 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 02 17:42:36 bedroom volumio[1244]: No state is present for card vc4hdmi1 Oct 02 17:42:36 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 02 17:42:36 bedroom volumio[1244]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 02 17:42:36 bedroom volumio[1244]: Hardware is initialized using a generic method Oct 02 17:42:36 bedroom volumio[1244]: No state is present for card vc4hdmi1 Oct 02 17:42:36 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Oct 02 17:42:36 bedroom volumio[1244]: No state is present for card sndrpihifiberry Oct 02 17:42:36 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Oct 02 17:42:36 bedroom volumio[1244]: Found hardware: "RPi-WM8804" "" "" "" "" Oct 02 17:42:36 bedroom volumio[1244]: Hardware is initialized using a generic method Oct 02 17:42:36 bedroom volumio[1244]: No state is present for card sndrpihifiberry Oct 02 17:42:36 bedroom volumio[1244]: info: Output device has changed, restarting MPD Oct 02 17:42:36 bedroom volumio[1244]: info: Output device has changed, restarting Shairport Sync Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:36 bedroom sudo[9848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 17:42:36 bedroom sudo[9848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom sudo[9848]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom sudo[9852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 17:42:36 bedroom sudo[9852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom volumio[1244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 17:42:36 bedroom volumio[1244]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:36 bedroom systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 02 17:42:36 bedroom volumio[1244]: info: QobuzConnect: setDeactiveState invoked Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:36 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:36 bedroom volumio[1244]: info: Output device has changed, restarting MPD Oct 02 17:42:36 bedroom systemd[1]: mpd.service: Deactivated successfully. Oct 02 17:42:36 bedroom systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 17:42:36 bedroom systemd[1]: mpd.service: Consumed 1.401s CPU time. Oct 02 17:42:36 bedroom systemd[1]: mpd.socket: Deactivated successfully. Oct 02 17:42:36 bedroom systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 17:42:36 bedroom systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 17:42:36 bedroom volumio[1244]: info: Output device has changed, restarting Shairport Sync Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:36 bedroom sudo[9860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 17:42:36 bedroom sudo[9860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom sudo[9863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 17:42:36 bedroom sudo[9863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom sudo[9863]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 17:42:36 bedroom volumio[1244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 17:42:36 bedroom volumio[1244]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:36 bedroom systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 17:42:36 bedroom volumio[1244]: info: QobuzConnect: setDeactiveState invoked Oct 02 17:42:36 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:36 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:36 bedroom sudo[9866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 17:42:36 bedroom sudo[9866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom sudo[9860]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom qobuz-connect[2423]: 20261002 17:42:36.767 [2423.2423] INFO SampleApp: shat down connection on UNIX socket Oct 02 17:42:36 bedroom systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 17:42:36 bedroom systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 17:42:36 bedroom systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 17:42:36 bedroom sudo[9829]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom systemd[1]: mpd.service: Deactivated successfully. Oct 02 17:42:36 bedroom systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 17:42:36 bedroom systemd[1]: mpd.socket: Deactivated successfully. Oct 02 17:42:36 bedroom systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 17:42:36 bedroom systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 17:42:36 bedroom sudo[9878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 17:42:36 bedroom sudo[9878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom sudo[9884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 17:42:36 bedroom sudo[9884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 17:42:36 bedroom systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 17:42:36 bedroom sudo[9883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 17:42:36 bedroom sudo[9883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:36 bedroom sudo[9878]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:36 bedroom sudo[9884]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 17:42:37 bedroom sudo[9896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom sudo[9904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 17:42:37 bedroom sudo[9904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom sudo[9891]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 17:42:37 bedroom sudo[9891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 17:42:37 bedroom sudo[9891]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9883]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom volumio[1244]: info: MPD Permissions set Oct 02 17:42:37 bedroom volumio[1244]: info: MPD Permissions set Oct 02 17:42:37 bedroom volumio[1244]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 02 17:42:37 bedroom volumio[1244]: info: QobuzConnect: setDeactiveState invoked Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:37 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:37 bedroom volumio[1244]: info: Executing endpoint qc_getconfig Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 17:42:37 bedroom sudo[9904]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.119 [9881.9881] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.123 [9881.9881] INFO VolumeManager: [0xd35f28]: Setting new playback volume: 75 Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.123 [9881.9881] INFO VolumeManager: [0xd35f28]: Setting new mute state: 0 Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.123 [9881.9881] INFO AudioStreamManager: [0xd35c80]: Setting new audio download buffer size: 1048576 Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.123 [9881.9881] INFO QobuzConnect: [0xd367f0]: Client initialized! Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.123 [9881.9881] INFO SampleApp: Starting Avahi advertising, name: Bedroom, service name: _qobuz-connect._tcp Oct 02 17:42:37 bedroom volumio[1244]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 17:42:37 bedroom volumio[1244]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom sudo[9896]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 17:42:37 bedroom sudo[9914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom sudo[9921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 17:42:37 bedroom sudo[9921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.180 [9881.9881] INFO LocalConfigManager: [0xd359a8]: Starting Local Configuration server Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.180 [9881.9881] INFO SampleApp: Starting Local configuration server Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.181 [9881.9881] INFO SampleApp: Connected to UNIX socket client 0xd20818 Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: Starting Shairport Sync Oct 02 17:42:37 bedroom qobuz-connect[9881]: 20261002 17:42:37.214 [9881.9881] INFO SampleApp: Stopping Local configuration server Oct 02 17:42:37 bedroom systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 17:42:37 bedroom volumio[1244]: info: Starting Shairport Sync Oct 02 17:42:37 bedroom volumio[1244]: info: Preparing to generate the ALSA configuration file Oct 02 17:42:37 bedroom sudo[9932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 17:42:37 bedroom sudo[9932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom volumio[1244]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 17:42:37 bedroom volumio[1244]: info: Reading ALSA contributions from plugins. Oct 02 17:42:37 bedroom sudo[9934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 17:42:37 bedroom sudo[9934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom volumio[1244]: info: Asound.conf file written Oct 02 17:42:37 bedroom sudo[9940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 17:42:37 bedroom sudo[9940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom sudo[9940]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 17:42:37 bedroom systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 17:42:37 bedroom systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 17:42:37 bedroom systemd[1]: shairport-sync.service: Consumed 12.186s CPU time. Oct 02 17:42:37 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 17:42:37 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 02 17:42:37 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 02 17:42:37 bedroom volumio[1244]: No state is present for card vc4hdmi0 Oct 02 17:42:37 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 02 17:42:37 bedroom volumio[1244]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 02 17:42:37 bedroom volumio[1244]: Hardware is initialized using a generic method Oct 02 17:42:37 bedroom volumio[1244]: No state is present for card vc4hdmi0 Oct 02 17:42:37 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 02 17:42:37 bedroom volumio[1244]: No state is present for card vc4hdmi1 Oct 02 17:42:37 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 02 17:42:37 bedroom volumio[1244]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 02 17:42:37 bedroom volumio[1244]: Hardware is initialized using a generic method Oct 02 17:42:37 bedroom volumio[1244]: No state is present for card vc4hdmi1 Oct 02 17:42:37 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Oct 02 17:42:37 bedroom volumio[1244]: No state is present for card sndrpihifiberry Oct 02 17:42:37 bedroom volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Oct 02 17:42:37 bedroom volumio[1244]: Found hardware: "RPi-WM8804" "" "" "" "" Oct 02 17:42:37 bedroom volumio[1244]: Hardware is initialized using a generic method Oct 02 17:42:37 bedroom volumio[1244]: No state is present for card sndrpihifiberry Oct 02 17:42:37 bedroom volumio[1244]: info: Output device has changed, restarting MPD Oct 02 17:42:37 bedroom volumio[1244]: info: Output device has changed, restarting Shairport Sync Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:37 bedroom sudo[9947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 17:42:37 bedroom sudo[9947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 17:42:37 bedroom sudo[9947]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9932]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 17:42:37 bedroom sudo[9949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom sudo[9934]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom volumio[1244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 17:42:37 bedroom systemd[1]: mpd.service: Deactivated successfully. Oct 02 17:42:37 bedroom systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 17:42:37 bedroom systemd[1]: mpd.socket: Deactivated successfully. Oct 02 17:42:37 bedroom systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 17:42:37 bedroom systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 17:42:37 bedroom volumio[1244]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 17:42:37 bedroom systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 17:42:37 bedroom volumio[1244]: info: QobuzConnect: setDeactiveState invoked Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 02 17:42:37 bedroom volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 02 17:42:37 bedroom volumio[1244]: info: Preparing to generate the ALSA configuration file Oct 02 17:42:37 bedroom sudo[9974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 17:42:37 bedroom sudo[9974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom sudo[9978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 17:42:37 bedroom sudo[9978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom volumio[1244]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 02 17:42:37 bedroom volumio[1244]: info: PLUGIN onReboot : networkfs Oct 02 17:42:37 bedroom volumio[1244]: info: PLUGIN onReboot : touch_display Oct 02 17:42:37 bedroom sudo[9974]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9971]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 17:42:37 bedroom sudo[9971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 17:42:37 bedroom sudo[9971]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Oct 02 17:42:37 bedroom sudo[9988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom sudo[9985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 17:42:37 bedroom sudo[9985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom sudo[9978]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9985]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Oct 02 17:42:37 bedroom sudo[9997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 17:42:37 bedroom sudo[9997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom startx[2973]: xinit: connection to X server lost Oct 02 17:42:37 bedroom startx[2973]: Oct 02 17:42:37 bedroom sudo[10001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot Oct 02 17:42:37 bedroom sudo[10001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 17:42:37 bedroom volumio[1244]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 17:42:37 bedroom volumio[1244]: info: Reading ALSA contributions from plugins. Oct 02 17:42:37 bedroom systemd-logind[776]: The system will reboot now! Oct 02 17:42:37 bedroom volumio[1244]: info: MPD Permissions set Oct 02 17:42:37 bedroom volumio[1244]: info: Shairport-Sync Started Oct 02 17:42:37 bedroom volumio[1244]: info: Shairport-Sync Started Oct 02 17:42:37 bedroom systemd-logind[776]: System is rebooting. Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:37 bedroom sudo[9949]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9866]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9921]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9997]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9852]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:37 bedroom sudo[9914]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:38 bedroom sudo[10001]: pam_unix(sudo:session): session closed for user root Oct 02 17:42:38 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:42:38 bedroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:42:38 bedroom volumio[1244]: info: Rebooting Oct 02 17:42:38 bedroom volumio[1244]: error: QobuzConnect: could not execute /bin/systemctl to restart qobuz connect process. Reason: Job for qobuz-connect.service canceled. Oct 02 17:42:38 bedroom volumio[1244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:42:38 bedroom volumio[1244]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "undefined".] { Oct 02 17:42:38 bedroom volumio[1244]: code: 'ERR_UNHANDLED_REJECTION' Oct 02 17:42:38 bedroom volumio[1244]: } Oct 02 17:42:38 bedroom volumio[1244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:42:38 bedroom startx[2973]: waiting for X server to shut down Oct 02 17:42:38 bedroom startx[2974]: (II) Server terminated successfully (0). Closing log file. Oct 02 17:42:38 bedroom systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. Oct 02 17:42:38 bedroom systemd[1]: Stopped target bluetooth.target - Bluetooth Support. Oct 02 17:42:38 bedroom systemd[1]: Stopped target graphical.target - Graphical Interface. Oct 02 17:42:38 bedroom systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 02 17:42:38 bedroom systemd[1]: Stopped target rpc_pipefs.target. Oct 02 17:42:38 bedroom systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. Oct 02 17:42:38 bedroom systemd[1]: Stopped target timers.target - Timer Units. Oct 02 17:42:38 bedroom systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Oct 02 17:42:38 bedroom systemd[1]: apt-daily.timer: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped apt-daily.timer - Daily apt download activities. Oct 02 17:42:38 bedroom systemd[1]: dpkg-db-backup.timer: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. Oct 02 17:42:38 bedroom systemd[1]: e2scrub_all.timer: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Oct 02 17:42:38 bedroom systemd[1]: fstrim.timer: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. Oct 02 17:42:38 bedroom systemd[1]: man-db.timer: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped man-db.timer - Daily man-db regeneration. Oct 02 17:42:38 bedroom systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Oct 02 17:42:38 bedroom systemd[1]: setdatetime-helper.timer: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Oct 02 17:42:38 bedroom systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 02 17:42:38 bedroom systemd[1]: systemd-rfkill.socket: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 02 17:42:38 bedroom systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... Oct 02 17:42:38 bedroom systemd[1]: Stopping bluealsa.service - BlueALSA service... Oct 02 17:42:38 bedroom bluealsa[943]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Oct 02 17:42:38 bedroom systemd[1]: rpi-display-backlight.service - Turns off Raspberry Pi display backlight on shutdown/reboot was skipped because of an unmet condition check (ConditionPathIsDirectory=/proc/device-tree/rpi_backlight). Oct 02 17:42:38 bedroom bluetoothd[799]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Oct 02 17:42:38 bedroom bluetoothd[799]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Oct 02 17:42:38 bedroom systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Oct 02 17:42:38 bedroom autossh[2372]: received signal to exit (15) Oct 02 17:42:38 bedroom bluetoothd[799]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Oct 02 17:42:38 bedroom bluetoothd[799]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Oct 02 17:42:38 bedroom systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... Oct 02 17:42:38 bedroom systemd[1]: Stopping upower.service - Daemon for power management... Oct 02 17:42:38 bedroom systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... Oct 02 17:42:38 bedroom systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Oct 02 17:42:38 bedroom systemd[1]: bluealsa.service: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped bluealsa.service - BlueALSA service. Oct 02 17:42:38 bedroom systemd[1]: upower.service: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped upower.service - Daemon for power management. Oct 02 17:42:38 bedroom volumiobt[10025]: INFO [BTSTART] Disconnecting all Bluetooth devices... Oct 02 17:42:38 bedroom systemd[1]: volumio5-onboarding.service: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 17:42:38 bedroom systemd[1]: volumio5-onboarding.service: Consumed 3.064s CPU time. Oct 02 17:42:38 bedroom systemd[1]: sshtunnel.service: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Oct 02 17:42:38 bedroom systemd[1]: mpd.service: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 17:42:38 bedroom systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. Oct 02 17:42:38 bedroom systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 02 17:42:38 bedroom systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. Oct 02 17:42:38 bedroom bluetoothd[799]: Path / reserved for Adv Monitor app :1.69 Oct 02 17:42:38 bedroom bluetoothd[799]: Adv Monitor app :1.69 disconnected from D-Bus Oct 02 17:42:38 bedroom volumiobt[10034]: Attempting to disconnect from 7B:91:04:3A:59:3F Oct 02 17:42:38 bedroom volumiobt[10034]: [NEW] Media /org/bluez/hci0 Oct 02 17:42:38 bedroom volumiobt[10034]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 02 17:42:38 bedroom volumiobt[10034]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 02 17:42:38 bedroom volumiobt[10034]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 02 17:42:38 bedroom bluetoothd[799]: Path / reserved for Adv Monitor app :1.70 Oct 02 17:42:38 bedroom volumiobt[10034]: AdvertisementMonitor path registered Oct 02 17:42:38 bedroom sudo[10037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 17:41' Oct 02 17:42:38 bedroom sudo[10037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"