Mar 04 19:49:16 loe kernel: hwmon hwmon1: Voltage normalised Mar 04 19:49:19 loe kernel: hwmon hwmon1: Undervoltage detected! Mar 04 19:49:31 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:31.748+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.178.147:44312 @ 0x25c2960" latency=15.182845ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 19:49:31 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:31.748+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.147:44312 @ 0x25c2960" latency=14.366855ms timeout=10s Mar 04 19:49:31 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:31.748+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" Mar 04 19:49:31 loe volumio[13512]: info: Received Get System Info Mar 04 19:49:31 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:49:31 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:49:31 loe volumio[13512]: info: Discovery: Getting this device information Mar 04 19:49:31 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:49:31 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:49:31 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:31.759+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" name=Loe Mar 04 19:49:31 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:31.761+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" language=nl Mar 04 19:49:31 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 19:49:31 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:31.768+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" timezone=Europe/Amsterdam Mar 04 19:49:31 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:31.771+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" available=true connected=true macAddress=b8:27:eb:68:fe:6d ip4Address=192.168.178.145/24 ip6Address= Mar 04 19:49:31 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:31.775+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 04 19:49:31 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:31.776+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" setupComplete=true Mar 04 19:49:31 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 04 19:49:31 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 19:49:31 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:49:31 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:49:31 loe volumio[13512]: amixer -c 3 info | grep "snd_rpi_hifiberry_dacplus" Mar 04 19:49:31 loe volumio[13512]: Card sysdefault:3 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplus' Mar 04 19:49:31 loe volumio[13512]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 04 19:49:31 loe volumio[13512]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 04 19:49:32 loe volumio[13512]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 04 19:49:32 loe volumio[13512]: amixer -c 3 info | grep "snd_rpi_hifiberry_dacplus" Mar 04 19:49:32 loe volumio[13512]: Card sysdefault:3 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplus' Mar 04 19:49:32 loe volumio[13512]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Mar 04 19:49:32 loe volumio[13512]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 04 19:49:32 loe volumio[13512]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 04 19:49:32 loe volumio[13512]: amixer -c 2 info | grep "vc4-hdmi" Mar 04 19:49:32 loe volumio[13512]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.131+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" selectedOutputId=3 Mar 04 19:49:32 loe volumio[13512]: info: Received Get System Info Mar 04 19:49:32 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:49:32 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:49:32 loe volumio[13512]: info: Discovery: Getting this device information Mar 04 19:49:32 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:49:32 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.161+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" currentVersion=4.096 latestVersion=4.096 Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.162+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.178.147:44312 @ 0x25c2960" status=UPDATE_STATUS_NONE progress=0 Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.163+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" userId= Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.163+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" providers=3 Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.164+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" plugins=62 Mar 04 19:49:32 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.177+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" state=STATUS_UNSPECIFIED positionMs= volume=5 Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.178+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:44312 @ 0x25c2960" id= title= Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.182+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.178.147:44312 Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.182+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.178.147:44312 Mar 04 19:49:32 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:32.192+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.147:44052 Mar 04 19:49:43 loe kernel: hwmon hwmon1: Voltage normalised Mar 04 19:49:46 loe kernel: hwmon hwmon1: Undervoltage detected! Mar 04 19:49:50 loe kernel: hwmon hwmon1: Voltage normalised Mar 04 19:49:52 loe kernel: hwmon hwmon1: Undervoltage detected! Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.187+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.147:44052 @ 0x279e2d0" latency=19.65963ms timeout=10s Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.187+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.178.147:44052 @ 0x279e2d0" latency=21.45286ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.188+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" Mar 04 19:49:53 loe volumio[13512]: info: Received Get System Info Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:49:53 loe volumio[13512]: info: Discovery: Getting this device information Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.197+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" name=Loe Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.199+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" language=nl Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.207+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" timezone=Europe/Amsterdam Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.209+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" available=true connected=true macAddress=b8:27:eb:68:fe:6d ip4Address=192.168.178.145/24 ip6Address= Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.212+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.212+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" setupComplete=true Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:49:53 loe volumio[13512]: amixer -c 3 info | grep "snd_rpi_hifiberry_dacplus" Mar 04 19:49:53 loe volumio[13512]: Card sysdefault:3 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplus' Mar 04 19:49:53 loe volumio[13512]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 04 19:49:53 loe volumio[13512]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 04 19:49:53 loe volumio[13512]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 04 19:49:53 loe volumio[13512]: amixer -c 3 info | grep "snd_rpi_hifiberry_dacplus" Mar 04 19:49:53 loe volumio[13512]: Card sysdefault:3 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplus' Mar 04 19:49:53 loe volumio[13512]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Mar 04 19:49:53 loe volumio[13512]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 04 19:49:53 loe volumio[13512]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 04 19:49:53 loe volumio[13512]: amixer -c 2 info | grep "vc4-hdmi" Mar 04 19:49:53 loe volumio[13512]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.546+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" selectedOutputId=3 Mar 04 19:49:53 loe volumio[13512]: info: Received Get System Info Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:49:53 loe volumio[13512]: info: Discovery: Getting this device information Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.570+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" currentVersion=4.096 latestVersion=4.096 Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.571+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" status=UPDATE_STATUS_NONE progress=0 Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.572+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" userId= Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.572+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" providers=3 Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.572+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" plugins=62 Mar 04 19:49:53 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.579+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" state=STATUS_UNSPECIFIED positionMs= volume=5 Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.580+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:44052 @ 0x279e2d0" id= title= Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.584+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.178.147:44052 Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.584+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.178.147:44052 Mar 04 19:49:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:49:53.592+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.147:52756 Mar 04 19:49:56 loe sudo[15701]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 19:49:56 loe sudo[15701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:49:56 loe sudo[15701]: pam_unix(sudo:session): session closed for user root Mar 04 19:49:56 loe sudo[15703]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 19:49:56 loe sudo[15703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:49:56 loe sudo[15703]: pam_unix(sudo:session): session closed for user root Mar 04 19:49:56 loe volumio[13512]: verbose: New Socket.io Connection to 192.168.178.145 from 192.168.178.147 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 04 19:49:56 loe volumio[13512]: info: Received Get System Info Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:49:56 loe volumio[13512]: info: Discovery: Getting this device information Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:49:56 loe volumio-remote-updater[708]: Test mode disabled Mar 04 19:49:56 loe volumio-remote-updater[708]: Alpha mode disabled Mar 04 19:49:56 loe volumio-remote-updater[708]: Alpha legacy test mode disabled Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:49:56 loe volumio[13512]: info: Retrieving Cloud Streaming UI Mar 04 19:49:56 loe volumio[13512]: info: Getting Tidal Cloud Configuration Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:49:56 loe volumio[13512]: info: Getting Qobuz Cloud Configuration Mar 04 19:49:56 loe volumio[13512]: info: Asking plugin for UI Config Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:49:56 loe volumio[13512]: info: Getting Spotify Cloud Configuration Mar 04 19:49:56 loe volumio[13512]: info: Asking plugin for UI Config Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:49:56 loe volumio[13512]: info: Saving Spotify Acccount Mar 04 19:49:56 loe volumio[13512]: info: Got it Mar 04 19:49:56 loe volumio[13512]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 04 19:49:56 loe volumio[13512]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 04 19:49:56 loe volumio[13512]: info: Got Tidal Cloud Configuration Mar 04 19:49:56 loe volumio[13512]: info: Got it Mar 04 19:49:56 loe volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:49:57 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 04 19:49:57 loe volumio[13512]: info: Loading i18n strings for locale nl Mar 04 19:49:57 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 04 19:49:57 loe volumio[13512]: info: Setting timezone to Europe/Amsterdam Mar 04 19:49:57 loe sudo[15716]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 04 19:49:57 loe sudo[15716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:49:57 loe sudo[15716]: pam_unix(sudo:session): session closed for user root Mar 04 19:49:57 loe sudo[15720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime Mar 04 19:49:57 loe sudo[15720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:49:57 loe sudo[15720]: pam_unix(sudo:session): session closed for user root Mar 04 19:49:57 loe sudo[15725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 04 19:49:57 loe sudo[15725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:49:57 loe sudo[15725]: pam_unix(sudo:session): session closed for user root Mar 04 19:49:58 loe sudo[15729]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam Mar 04 19:49:58 loe sudo[15729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:49:58 loe dbus-daemon[696]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.243' (uid=0 pid=15730 comm="/usr/bin/timedatectl set-timezone Europe/Amsterdam") Mar 04 19:49:58 loe systemd[1]: Starting systemd-timedated.service - Time & Date Service... Mar 04 19:49:58 loe dbus-daemon[696]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 04 19:49:58 loe systemd[1]: Started systemd-timedated.service - Time & Date Service. Mar 04 19:49:58 loe sudo[15729]: pam_unix(sudo:session): session closed for user root Mar 04 19:49:58 loe volumio[13512]: info: Fetching Streaming Services browse cache Mar 04 19:49:58 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 19:49:58 loe volumio[13512]: Updating browse sources language Mar 04 19:49:58 loe volumio[13512]: Cannot find translation for source TIDAL Mar 04 19:49:58 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 19:49:58 loe volumio[13512]: Cannot find translation for source TIDAL Mar 04 19:49:59 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 04 19:50:00 loe volumio[13512]: info: Loading i18n strings for locale nl Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 04 19:50:00 loe volumio[13512]: info: Setting timezone to Europe/Amsterdam Mar 04 19:50:00 loe sudo[15734]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 04 19:50:00 loe sudo[15734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe sudo[15734]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:00 loe sudo[15738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime Mar 04 19:50:00 loe sudo[15738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe sudo[15738]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:00 loe sudo[15742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 04 19:50:00 loe sudo[15742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe sudo[15742]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:00 loe sudo[15746]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam Mar 04 19:50:00 loe sudo[15746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe sudo[15746]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 04 19:50:00 loe sudo[15749]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 04 19:50:00 loe sudo[15749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:00 loe volumio[13512]: Updating browse sources language Mar 04 19:50:00 loe volumio[13512]: Cannot find translation for source TIDAL Mar 04 19:50:00 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 19:50:00 loe volumio[13512]: Cannot find translation for source TIDAL Mar 04 19:50:00 loe volumio[13512]: info: Fetching Streaming Services browse cache Mar 04 19:50:01 loe sudo[15749]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:01 loe volumio5-onboarding[2437]: time=2026-03-04T19:50:01.876+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.147:52756 @ 0x2400540" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 04 19:50:01 loe volumio[13512]: info: Received Get System Info Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:50:01 loe volumio[13512]: info: Discovery: Getting this device information Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 19:50:01 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 19:50:14 loe volumio[13512]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 04 19:50:14 loe volumio[13512]: info: Received Get System Version Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 19:50:14 loe volumio[13512]: info: Received Get System Info Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 19:50:14 loe volumio[13512]: info: Discovery: Getting this device information Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:14 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Mar 04 19:50:19 loe volumio[13512]: info: System name has changed, restarting Shairport Sync Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 19:50:19 loe volumio[13512]: info: Updating MyVolumio device info Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: QobuzConnect: setDeactiveState invoked Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:19 loe vtcs[15318]: [2026-03-04 19:50:19.190] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 04 19:50:19 loe vtcs[15318]: [2026-03-04 19:50:19.191] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 04 19:50:19 loe volumio[13512]: info: Discovery: Restarting Advertising Mar 04 19:50:19 loe volumio[13512]: info: Discovery: Stopping existing advertisement Mar 04 19:50:19 loe sudo[15785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 19:50:19 loe sudo[15785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15787]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 04 19:50:19 loe sudo[15787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 04 19:50:19 loe systemd[1]: vtcs.service: Killing process 15332 (vtcs) with signal SIGKILL. Mar 04 19:50:19 loe sudo[15791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 19:50:19 loe sudo[15791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15787]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe systemd[1]: vtcs.service: Deactivated successfully. Mar 04 19:50:19 loe systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 04 19:50:19 loe sudo[15798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 04 19:50:19 loe sudo[15798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15785]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:19 loe volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:50:19 loe volumio[13512]: info: Starting Shairport Sync Mar 04 19:50:19 loe sudo[15798]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe sudo[15791]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe sudo[15807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 19:50:19 loe sudo[15807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Mar 04 19:50:19 loe sudo[15805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe sudo[15805]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 loe sudo[15808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 04 19:50:19 loe sudo[15808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 loe volumio[13512]: info: Permissions for /etc/hosts set Mar 04 19:50:19 loe volumio[13512]: info: Hostname now is volumio Mar 04 19:50:19 loe systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 04 19:50:19 loe systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 19:50:19 loe qobuz-connect[15262]: 20260304 19:50:19.721 [15262.15262] INFO SampleApp: Stopping Local configuration server Mar 04 19:50:19 loe systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 19:50:19 loe systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:19 loe systemd[1]: shairport-sync.service: Consumed 2.707s CPU time. Mar 04 19:50:19 loe sudo[15814]: volumio : unable to resolve host loe: System error Mar 04 19:50:19 loe sudo[15814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio Mar 04 19:50:19 loe sudo[15814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 volumio sudo[15814]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 volumio volumio[13512]: info: New hostname set Mar 04 19:50:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:19 volumio sudo[15807]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 volumio sudo[15816]: volumio : unable to resolve host loe: System error Mar 04 19:50:19 volumio volumio[13512]: info: Shairport-Sync Started Mar 04 19:50:19 volumio sudo[15816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Mar 04 19:50:19 volumio sudo[15816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:19 volumio sudo[15816]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:19 volumio volumio[13512]: info: Permissions for /etc/avahi/services/volumio.service Mar 04 19:50:19 volumio avahi-daemon[695]: Files changed, reloading. Mar 04 19:50:19 volumio volumio[13512]: info: Avahi name changed to volumio Mar 04 19:50:19 volumio avahi-daemon[695]: Service group file /services/volumio.service changed, reloading. Mar 04 19:50:20 volumio volumio[13512]: info: Successfully Updated MyVolumio device Mar 04 19:50:20 volumio volumio[13512]: info: Discovery: A device disappeared from network Mar 04 19:50:20 volumio volumio[13512]: info: Discovery: Device loe disappeared from network Mar 04 19:50:20 volumio volumio[13512]: info: Discovery: A device disappeared from network Mar 04 19:50:20 volumio avahi-daemon[695]: Service "Volumio" (/services/volumio.service) successfully established. Mar 04 19:50:20 volumio qobuz-connect[15262]: 20260304 19:50:20.819 [15262.15262] INFO SampleApp: shat down connection on UNIX socket Mar 04 19:50:20 volumio volumio[13512]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 04 19:50:20 volumio volumio[13512]: info: QobuzConnect: setDeactiveState invoked Mar 04 19:50:20 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:20 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 04 19:50:20 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:20 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:20 volumio sudo[15808]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:21 volumio volumio[13512]: info: Executing endpoint qc_getconfig Mar 04 19:50:21 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.055 [15834.15834] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 04 19:50:21 volumio volumio[13512]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 04 19:50:21 volumio volumio[13512]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.067 [15834.15834] INFO VolumeManager: [0x200408]: Setting new playback volume: 75 Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.067 [15834.15834] INFO VolumeManager: [0x200408]: Setting new mute state: 0 Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.067 [15834.15834] INFO QobuzConnect: [0x200dd8]: Client initialized! Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.067 [15834.15834] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.113 [15834.15834] INFO LocalConfigManager: [0x1ffce8]: Starting Local Configuration server Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.113 [15834.15834] INFO SampleApp: Starting Local configuration server Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.114 [15834.15834] INFO SampleApp: Connected to UNIX socket client 0x1ea818 Mar 04 19:50:21 volumio qobuz-connect[15834]: 20260304 19:50:21.205 [15834.15834] INFO SampleApp: Playback volume changed: 75 Mar 04 19:50:21 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 04 19:50:22 volumio volumio[13512]: info: Preparing to save Alsa Options, stopping services first Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:22 volumio volumio[13512]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"HiFiBerry DAC Plus"},"output_device":{"value":1,"label":"HiFiBerry DAC Plus"},"disallowPush":true} Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 04 19:50:22 volumio volumio[13512]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 04 19:50:22 volumio volumio[13512]: info: Disabling external Volume Control Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Preparing to generate the ALSA configuration file Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Retrieving Cloud Streaming UI Mar 04 19:50:22 volumio volumio[13512]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 04 19:50:22 volumio volumio[13512]: info: Reading ALSA contributions from plugins. Mar 04 19:50:22 volumio volumio[13512]: info: TidalConnect service stoped! Mar 04 19:50:22 volumio volumio[13512]: info: TidalConnect service stoped! Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 19:50:22 volumio volumio[13512]: info: Getting Alsa Cards List without I2S DAC Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 19:50:22 volumio volumio[13512]: info: Getting Tidal Cloud Configuration Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Getting Qobuz Cloud Configuration Mar 04 19:50:22 volumio volumio[13512]: info: Asking plugin for UI Config Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Getting Spotify Cloud Configuration Mar 04 19:50:22 volumio volumio[13512]: info: Asking plugin for UI Config Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:22 volumio volumio[13512]: info: Saving Spotify Acccount Mar 04 19:50:22 volumio volumio[13512]: info: Got it Mar 04 19:50:22 volumio volumio[13512]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 04 19:50:22 volumio volumio[13512]: info: VolumeController:: Volume=5 Mute =false Mar 04 19:50:22 volumio volumio[13512]: info: CoreStateMachine::pushState Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::volumioPushState Mar 04 19:50:22 volumio volumio[13512]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:50:22 volumio volumio[13512]: info: MRS: Pushing multiroomSync output Mar 04 19:50:22 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.915+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:52756 @ 0x2400540" state=STATUS_UNSPECIFIED positionMs= volume=5 Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.918+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:52756 @ 0x2400540" id= title= Mar 04 19:50:22 volumio volumio[13512]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Mar 04 19:50:22 volumio volumio[13512]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.mjORZRjMx8YIlyGajvB2CXSYA5r2.a53384b16fd4d7a180fdac1b5068092c.state.status' Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.966+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.178.147:52756 Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.966+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.178.147:52756 Mar 04 19:50:22 volumio volumio5-onboarding[2437]: time=2026-03-04T19:50:22.973+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.147:39918 Mar 04 19:50:23 volumio volumio[13512]: info: Asound.conf file unchanged, so no further update is needed Mar 04 19:50:23 volumio volumio[13512]: info: Output device has changed, restarting MPD Mar 04 19:50:23 volumio volumio[13512]: info: Output device has changed, restarting Shairport Sync Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:23 volumio sudo[15889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 19:50:23 volumio sudo[15889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 19:50:23 volumio sudo[15891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15889]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio volumio[13512]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 19:50:23 volumio volumio[13512]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: QobuzConnect: setDeactiveState invoked Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::volumioGetState Mar 04 19:50:23 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 04 19:50:23 volumio sudo[15901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 19:50:23 volumio sudo[15901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 19:50:23 volumio sudo[15903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio volumio[13512]: info: Got Tidal Cloud Configuration Mar 04 19:50:23 volumio volumio[13512]: info: Got it Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:50:23 volumio volumio[13512]: info: MPD Permissions set Mar 04 19:50:23 volumio volumio[13512]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 04 19:50:23 volumio volumio[13512]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 04 19:50:23 volumio sudo[15911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 04 19:50:23 volumio sudo[15911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 19:50:23 volumio systemd[1]: mpd.service: Consumed 6.134s CPU time. Mar 04 19:50:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 04 19:50:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 19:50:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 19:50:23 volumio volumio[13512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 19:50:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 19:50:23 volumio volumio[13512]: info: Starting Shairport Sync Mar 04 19:50:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 19:50:23 volumio sudo[15920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 04 19:50:23 volumio sudo[15920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 19:50:23 volumio sudo[15924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio sudo[15911]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio sudo[15901]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio volumio[13512]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:50:23 volumio sudo[15903]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio volumio[13512]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Mar 04 19:50:23 volumio volumio[13512]: Job for vtcs.service canceled. Mar 04 19:50:23 volumio volumio[13512]: at ChildProcess.exithandler (node:child_process:421:12) Mar 04 19:50:23 volumio volumio[13512]: at ChildProcess.emit (node:events:514:28) Mar 04 19:50:23 volumio volumio[13512]: at maybeClose (node:internal/child_process:1105:16) Mar 04 19:50:23 volumio volumio[13512]: at Socket. (node:internal/child_process:457:11) Mar 04 19:50:23 volumio volumio[13512]: at Socket.emit (node:events:514:28) Mar 04 19:50:23 volumio volumio[13512]: at Pipe. (node:net:337:12) { Mar 04 19:50:23 volumio volumio[13512]: code: 1, Mar 04 19:50:23 volumio volumio[13512]: killed: false, Mar 04 19:50:23 volumio volumio[13512]: signal: null, Mar 04 19:50:23 volumio volumio[13512]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Mar 04 19:50:23 volumio volumio[13512]: stdout: '', Mar 04 19:50:23 volumio volumio[13512]: stderr: 'Job for vtcs.service canceled.\n' Mar 04 19:50:23 volumio volumio[13512]: } Mar 04 19:50:23 volumio volumio[13512]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:50:23 volumio sudo[15926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 04 19:50:23 volumio sudo[15926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 19:50:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 19:50:23 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 04 19:50:23 volumio sudo[15920]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 19:50:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:23 volumio systemd[1]: shairport-sync.service: Consumed 1.690s CPU time. Mar 04 19:50:23 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 04 19:50:23 volumio qobuz-connect[15834]: 20260304 19:50:23.880 [15834.15834] INFO SampleApp: Stopping Local configuration server Mar 04 19:50:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 19:50:23 volumio sudo[15924]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:23 volumio sudo[15921]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 04 19:50:23 volumio sudo[15921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 19:50:23 volumio sudo[15921]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:25 volumio qobuz-connect[15834]: 20260304 19:50:25.122 [15834.15834] INFO SampleApp: shat down connection on UNIX socket Mar 04 19:50:25 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 04 19:50:25 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:25 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 19:50:25 volumio sudo[15926]: pam_unix(sudo:session): session closed for user root Mar 04 19:50:25 volumio sudo[15963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 19:49' Mar 04 19:50:25 volumio sudo[15963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"