May 26 23:00:00 lab-pa volumio[1245]: info: CoreCommandRouter::Close All Modals sent May 26 23:00:04 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:04.775+02:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.51:47378 error="websocket: close 1006 (abnormal closure): unexpected EOF" May 26 23:00:04 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:04.776+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.51:47378 May 26 23:00:04 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:04.776+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.51:47378 May 26 23:00:05 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:05.986+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.51:56494 May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.039+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.51:56494 @ 0x18e6f30" latency=-56.160446ms platform=PLATFORM_ANDROID version=5.260413.0 May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.042+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.51:56494 @ 0x18e6f30" latency=-53.399462ms timeout=10s May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.042+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" May 26 23:00:06 lab-pa volumio[1245]: info: Received Get System Info May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 23:00:06 lab-pa volumio[1245]: info: Discovery: Getting this device information May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:00:06 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.053+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" name=lab-pa May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.055+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" language=da May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.061+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" timezone=Europe/Copenhagen May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.063+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" available=true connected=true macAddress=b8:27:eb:ec:db:15 ip4Address=192.168.0.54/24 ip6Address= May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.066+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.067+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" setupComplete=true May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:00:06 lab-pa volumio[1245]: amixer -c 3 info | grep "HiFiBerry Digi+ Pro HiFi wm8804-spdif-0" May 26 23:00:06 lab-pa volumio[1245]: amixer -c 0 info | grep "bcm2835 ALSA" May 26 23:00:06 lab-pa volumio[1245]: amixer -c 1 info | grep "bcm2835 Headphones" May 26 23:00:06 lab-pa volumio[1245]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' May 26 23:00:06 lab-pa volumio[1245]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 May 26 23:00:06 lab-pa volumio[1245]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 26 23:00:06 lab-pa volumio[1245]: {"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)"} May 26 23:00:06 lab-pa volumio[1245]: amixer -c 2 info | grep "vc4-hdmi" May 26 23:00:06 lab-pa volumio[1245]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' May 26 23:00:06 lab-pa volumio[1245]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 May 26 23:00:06 lab-pa volumio[1245]: /bin/sh: 1: /usr/local/bin/alsacap: not found May 26 23:00:06 lab-pa volumio[1245]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\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)"} May 26 23:00:06 lab-pa volumio[1245]: amixer -c 3 info | grep "snd_rpi_hifiberry_digi" May 26 23:00:06 lab-pa volumio[1245]: Card sysdefault:3 'sndrpihifiberry'/'snd_rpi_hifiberry_digi' May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.445+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" selectedOutputId=3 May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 23:00:06 lab-pa volumio[1245]: info: Discovery: Getting this device information May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:00:06 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 23:00:06 lab-pa volumio[1245]: info: Received Get System Info May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 23:00:06 lab-pa volumio[1245]: info: Discovery: Getting this device information May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:00:06 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.478+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" currentVersion=4.119 latestVersion=4.119 May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.479+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" status=UPDATE_STATUS_NONE progress=0 May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.480+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" userId= May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.481+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" providers=3 May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.481+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" plugins=65 May 26 23:00:06 lab-pa volumio[1245]: verbose: New Socket.io Connection to 192.168.0.54:3000 from 192.168.0.51 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:00:06 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.508+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" state=STATUS_PLAYING positionMs=130081 volume=100 May 26 23:00:06 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:06.509+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.51:56494 @ 0x18e6f30" id="mnt/NAS/MUSIK/_Jens_CD/Dire Straits/On Every Street/01 Calling Elvis.m4a" title="Calling Elvis" May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 23:00:06 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 23:00:07 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:07.268+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.51:56494 @ 0x18e6f30" latency=-56.5848ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 26 23:00:07 lab-pa sudo[2756]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 23:00:07 lab-pa sudo[2756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:00:07 lab-pa sudo[2756]: pam_unix(sudo:session): session closed for user root May 26 23:00:07 lab-pa sudo[2758]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 23:00:07 lab-pa sudo[2758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:00:07 lab-pa sudo[2758]: pam_unix(sudo:session): session closed for user root May 26 23:00:07 lab-pa volumio[1245]: verbose: New Socket.io Connection to 192.168.0.54 from 192.168.0.51 UA: Mozilla/5.0 (Linux; Android 16; SM-G990B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.121 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 26 23:00:08 lab-pa sudo[2762]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 23:00:08 lab-pa sudo[2762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:00:08 lab-pa sudo[2764]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 23:00:08 lab-pa sudo[2762]: pam_unix(sudo:session): session closed for user root May 26 23:00:08 lab-pa sudo[2764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:00:08 lab-pa sudo[2764]: pam_unix(sudo:session): session closed for user root May 26 23:00:08 lab-pa volumio[1245]: verbose: New Socket.io Connection to 192.168.0.54 from 192.168.0.51 UA: Mozilla/5.0 (Linux; Android 16; SM-G990B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.121 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 26 23:00:08 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:00:08 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:00:08 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 23:00:08 lab-pa volumio[1245]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 26 23:00:08 lab-pa volumio[1245]: info: Listing playlists May 26 23:00:08 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 26 23:00:08 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 23:00:08 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 23:00:08 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 23:00:17 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:00:17 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:00:26 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 23:00:26 lab-pa volumio[1245]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 26 23:00:26 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 26 23:00:26 lab-pa volumio[1245]: info: Received Get System Version May 26 23:00:26 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 23:00:26 lab-pa volumio[1245]: info: Received Get System Info May 26 23:00:26 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 23:00:26 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 23:00:26 lab-pa volumio[1245]: info: Discovery: Getting this device information May 26 23:00:26 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:00:26 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:00:26 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 23:00:37 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:37.248+02:00 level=INFO msg="new address was allocated" component=ble/conn old=7 new=8 May 26 23:00:37 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:00:37 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:00:48 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:00:48.704+02:00 level=INFO msg="new address was allocated" component=ble/conn old=8 new=9 May 26 23:01:00 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:01:00 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:01:20 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:01:20 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:01:20 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPause May 26 23:01:20 lab-pa volumio[1245]: info: CoreStateMachine::pause May 26 23:01:20 lab-pa volumio[1245]: info: CoreStateMachine::stPlaybackTimer May 26 23:01:20 lab-pa volumio[1245]: info: CoreStateMachine::servicePause May 26 23:01:20 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:20 lab-pa volumio[1245]: info: CoreCommandRouter::servicePause May 26 23:01:20 lab-pa volumio[1245]: info: ControllerMpd::pause May 26 23:01:20 lab-pa volumio[1245]: verbose: ControllerMpd::sendMpdCommand pause May 26 23:01:20 lab-pa volumio[1245]: info: sendMpdCommand pause took 3 milliseconds May 26 23:01:23 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPause May 26 23:01:23 lab-pa volumio[1245]: info: CoreStateMachine::pause May 26 23:01:24 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPause May 26 23:01:24 lab-pa volumio[1245]: info: CoreStateMachine::pause May 26 23:01:30 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetQueue May 26 23:01:30 lab-pa volumio[1245]: info: CoreStateMachine::getQueue May 26 23:01:30 lab-pa volumio[1245]: info: CorePlayQueue::getQueue May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:32 lab-pa volumio[1245]: info: Getting Alsa Cards List without I2S DAC May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 26 23:01:32 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 23:01:33 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 23:01:33 lab-pa volumio[1245]: info: Received Get System Info May 26 23:01:33 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 23:01:33 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 23:01:33 lab-pa volumio[1245]: info: Discovery: Getting this device information May 26 23:01:33 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:33 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:33 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 23:01:34 lab-pa volumio[1245]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 26 23:01:34 lab-pa volumio[1245]: info: Preparing to save Alsa Options, stopping services first May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:34 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPause May 26 23:01:34 lab-pa volumio[1245]: info: CoreStateMachine::pause May 26 23:01:34 lab-pa volumio[1245]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:34 lab-pa volumio[1245]: info: Device 3 does not have any Mixer Control Available May 26 23:01:34 lab-pa volumio[1245]: info: QobuzConnect: setDeactiveState invoked May 26 23:01:34 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:34 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:34 lab-pa vtcs[2448]: [2026-05-26 23:01:34.984] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected May 26 23:01:34 lab-pa vtcs[2448]: [2026-05-26 23:01:34.987] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 26 23:01:35 lab-pa sudo[2926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:35 lab-pa sudo[2926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 26 23:01:35 lab-pa volumio[1245]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 26 23:01:35 lab-pa volumio[1245]: info: Disabling external Volume Control May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:01:35 lab-pa volumio[1245]: info: CoreStateMachine::pushState May 26 23:01:35 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPushState May 26 23:01:35 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output update for this device May 26 23:01:35 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:35 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:35 lab-pa systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 26 23:01:35 lab-pa systemd[1]: vtcs.service: Killing process 2479 (vtcs) with signal SIGKILL. May 26 23:01:35 lab-pa systemd[1]: vtcs.service: Deactivated successfully. May 26 23:01:35 lab-pa systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 26 23:01:35 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:35.135+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%06 @ 0x18e6e10" state=STATUS_PAUSED positionMs=204557 volume=100 May 26 23:01:35 lab-pa sudo[2926]: pam_unix(sudo:session): session closed for user root May 26 23:01:35 lab-pa volumio[1245]: info: Preparing to generate the ALSA configuration file May 26 23:01:35 lab-pa sudo[2933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:35 lab-pa sudo[2933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:35 lab-pa volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 26 23:01:35 lab-pa volumio[1245]: info: Reading ALSA contributions from plugins. May 26 23:01:35 lab-pa sudo[2940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 26 23:01:35 lab-pa sudo[2940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:35 lab-pa volumio[1245]: info: Getting Alsa Cards List without I2S DAC May 26 23:01:35 lab-pa sudo[2933]: pam_unix(sudo:session): session closed for user root May 26 23:01:35 lab-pa sudo[2940]: pam_unix(sudo:session): session closed for user root May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:35 lab-pa sudo[2949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 26 23:01:35 lab-pa sudo[2949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 23:01:35 lab-pa systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 26 23:01:35 lab-pa qobuz-connect[2392]: 20260526 23:01:35.624 [2392.2392] INFO SampleApp: Stopping Local configuration server May 26 23:01:35 lab-pa volumio[1245]: info: Asound.conf file unchanged, so no further update is needed May 26 23:01:35 lab-pa volumio[1245]: info: Output device has changed, restarting MPD May 26 23:01:35 lab-pa volumio[1245]: info: Output device has changed, restarting Shairport Sync May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:35 lab-pa sudo[2958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 23:01:35 lab-pa sudo[2958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:35 lab-pa sudo[2958]: pam_unix(sudo:session): session closed for user root May 26 23:01:35 lab-pa sudo[2960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 23:01:35 lab-pa sudo[2960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:35 lab-pa volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 23:01:35 lab-pa volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: QobuzConnect: setDeactiveState invoked May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:35 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:35 lab-pa systemd[1]: Stopping mpd.service - Music Player Daemon... May 26 23:01:35 lab-pa sudo[2972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:35 lab-pa sudo[2972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:35 lab-pa volumio[1245]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 26 23:01:35 lab-pa sudo[2970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:35 lab-pa sudo[2970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 26 23:01:35 lab-pa volumio[1245]: info: Preparing to save Alsa Options, stopping services first May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:35 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPause May 26 23:01:35 lab-pa volumio[1245]: info: CoreStateMachine::pause May 26 23:01:35 lab-pa volumio[1245]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 26 23:01:35 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:36 lab-pa sudo[2979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 26 23:01:36 lab-pa sudo[2979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:36 lab-pa systemd[1]: mpd.service: Deactivated successfully. May 26 23:01:36 lab-pa systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 23:01:36 lab-pa systemd[1]: mpd.service: Consumed 6.309s CPU time. May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: Device 3 does not have any Mixer Control Available May 26 23:01:36 lab-pa volumio[1245]: info: QobuzConnect: setDeactiveState invoked May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:36 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:36 lab-pa sudo[2979]: pam_unix(sudo:session): session closed for user root May 26 23:01:36 lab-pa systemd[1]: mpd.socket: Deactivated successfully. May 26 23:01:36 lab-pa systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 23:01:36 lab-pa systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 23:01:36 lab-pa sudo[2993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:36 lab-pa sudo[2993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 26 23:01:36 lab-pa volumio[1245]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 26 23:01:36 lab-pa volumio[1245]: info: Disabling external Volume Control May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:01:36 lab-pa sudo[2992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 26 23:01:36 lab-pa sudo[2992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:36 lab-pa systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 23:01:36 lab-pa systemd[1]: Starting mpd.service - Music Player Daemon... May 26 23:01:36 lab-pa volumio[1245]: info: CoreStateMachine::pushState May 26 23:01:36 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPushState May 26 23:01:36 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output update for this device May 26 23:01:36 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:36 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:36 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:36.352+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%06 @ 0x18e6e10" state=STATUS_PAUSED positionMs=204557 volume=100 May 26 23:01:36 lab-pa volumio[1245]: info: Preparing to generate the ALSA configuration file May 26 23:01:36 lab-pa sudo[2972]: pam_unix(sudo:session): session closed for user root May 26 23:01:36 lab-pa sudo[3003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:36 lab-pa sudo[3003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:36 lab-pa volumio[1245]: info: MPD Permissions set May 26 23:01:36 lab-pa volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 26 23:01:36 lab-pa volumio[1245]: info: Reading ALSA contributions from plugins. May 26 23:01:36 lab-pa qobuz-connect[2392]: 20260526 23:01:36.594 [2392.2392] INFO SampleApp: shat down connection on UNIX socket May 26 23:01:36 lab-pa sudo[3010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 26 23:01:36 lab-pa sudo[2970]: pam_unix(sudo:session): session closed for user root May 26 23:01:36 lab-pa sudo[3010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:36 lab-pa systemd[1]: qobuz-connect.service: Deactivated successfully. May 26 23:01:36 lab-pa systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 26 23:01:36 lab-pa volumio[1245]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 26 23:01:36 lab-pa volumio[1245]: info: QobuzConnect: setDeactiveState invoked May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:36 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:36 lab-pa sudo[2993]: pam_unix(sudo:session): session closed for user root May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:36 lab-pa sudo[3001]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 23:01:36 lab-pa sudo[3001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 23:01:36 lab-pa sudo[3001]: pam_unix(sudo:session): session closed for user root May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:36 lab-pa volumio[1245]: info: Getting Alsa Cards List without I2S DAC May 26 23:01:36 lab-pa systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 26 23:01:36 lab-pa sudo[2992]: pam_unix(sudo:session): session closed for user root May 26 23:01:36 lab-pa sudo[2949]: pam_unix(sudo:session): session closed for user root May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 26 23:01:36 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:36 lab-pa sudo[3010]: pam_unix(sudo:session): session closed for user root May 26 23:01:37 lab-pa sudo[3032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 26 23:01:37 lab-pa sudo[3032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 23:01:37 lab-pa volumio[1245]: info: Starting Shairport Sync May 26 23:01:37 lab-pa sudo[3003]: pam_unix(sudo:session): session closed for user root May 26 23:01:37 lab-pa systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 26 23:01:37 lab-pa systemd[1]: qobuz-connect.service: Deactivated successfully. May 26 23:01:37 lab-pa systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 26 23:01:37 lab-pa volumio[1245]: info: Executing endpoint qc_getconfig May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 26 23:01:37 lab-pa sudo[3040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 23:01:37 lab-pa sudo[3040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:37 lab-pa volumio[1245]: info: Asound.conf file unchanged, so no further update is needed May 26 23:01:37 lab-pa volumio[1245]: info: Output device has changed, restarting MPD May 26 23:01:37 lab-pa systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 26 23:01:37 lab-pa sudo[3032]: pam_unix(sudo:session): session closed for user root May 26 23:01:37 lab-pa sudo[3047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 23:01:37 lab-pa volumio[1245]: info: Output device has changed, restarting Shairport Sync May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:37 lab-pa sudo[3047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:37 lab-pa systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 23:01:37 lab-pa systemd[1]: shairport-sync.service: Deactivated successfully. May 26 23:01:37 lab-pa systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 23:01:37 lab-pa systemd[1]: shairport-sync.service: Consumed 2.502s CPU time. May 26 23:01:37 lab-pa sudo[3047]: pam_unix(sudo:session): session closed for user root May 26 23:01:37 lab-pa systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 23:01:37 lab-pa sudo[3040]: pam_unix(sudo:session): session closed for user root May 26 23:01:37 lab-pa volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 23:01:37 lab-pa sudo[3052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 23:01:37 lab-pa sudo[3052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:37 lab-pa volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: QobuzConnect: setDeactiveState invoked May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:37 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:37 lab-pa systemd[1]: mpd.service: Deactivated successfully. May 26 23:01:37 lab-pa systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 23:01:37 lab-pa systemd[1]: mpd.service: Consumed 1.120s CPU time. May 26 23:01:37 lab-pa systemd[1]: mpd.socket: Deactivated successfully. May 26 23:01:37 lab-pa systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 23:01:37 lab-pa systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 23:01:37 lab-pa sudo[3073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:37 lab-pa sudo[3073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:37 lab-pa systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 23:01:37 lab-pa sudo[3076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:37 lab-pa sudo[3076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:37 lab-pa systemd[1]: Starting mpd.service - Music Player Daemon... May 26 23:01:37 lab-pa volumio[1245]: info: MPD Permissions set May 26 23:01:37 lab-pa volumio[1245]: info: Shairport-Sync Started May 26 23:01:37 lab-pa volumio[1245]: info: Executing endpoint qc_getconfig May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 26 23:01:37 lab-pa sudo[3086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 26 23:01:37 lab-pa sudo[3086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:37 lab-pa qobuz-connect[3042]: 20260526 23:01:37.900 [3042.3042] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 26 23:01:37 lab-pa qobuz-connect[3042]: 20260526 23:01:37.917 [3042.3042] INFO VolumeManager: [0xdb5f08]: Setting new playback volume: 75 May 26 23:01:37 lab-pa qobuz-connect[3042]: 20260526 23:01:37.917 [3042.3042] INFO VolumeManager: [0xdb5f08]: Setting new mute state: 0 May 26 23:01:37 lab-pa qobuz-connect[3042]: 20260526 23:01:37.917 [3042.3042] INFO AudioStreamManager: [0xdb5c60]: Setting new audio download buffer size: 1048576 May 26 23:01:37 lab-pa qobuz-connect[3042]: 20260526 23:01:37.917 [3042.3042] INFO QobuzConnect: [0xdb67d0]: Client initialized! May 26 23:01:37 lab-pa qobuz-connect[3042]: 20260526 23:01:37.917 [3042.3042] INFO SampleApp: Starting Avahi advertising, name: lab-pa, service name: _qobuz-connect._tcp May 26 23:01:37 lab-pa volumio[1245]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 26 23:01:37 lab-pa volumio[1245]: info: QobuzConnect: QOBUZ Connect daemon connected May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:37 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:38 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:38 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:38 lab-pa qobuz-connect[3042]: 20260526 23:01:38.017 [3042.3042] INFO LocalConfigManager: [0xdb5988]: Starting Local Configuration server May 26 23:01:38 lab-pa qobuz-connect[3042]: 20260526 23:01:38.017 [3042.3042] INFO SampleApp: Starting Local configuration server May 26 23:01:38 lab-pa qobuz-connect[3042]: 20260526 23:01:38.018 [3042.3042] INFO SampleApp: Connected to UNIX socket client 0xda0818 May 26 23:01:38 lab-pa volumio[1245]: info: Starting Shairport Sync May 26 23:01:38 lab-pa qobuz-connect[3042]: 20260526 23:01:38.050 [3042.3042] INFO SampleApp: Playback volume changed: 75 May 26 23:01:38 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:38 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:38 lab-pa sudo[3086]: pam_unix(sudo:session): session closed for user root May 26 23:01:38 lab-pa sudo[3099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 23:01:38 lab-pa sudo[3076]: pam_unix(sudo:session): session closed for user root May 26 23:01:38 lab-pa sudo[3099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:38 lab-pa sudo[3073]: pam_unix(sudo:session): session closed for user root May 26 23:01:38 lab-pa volumio[1245]: info: TidalConnect service stoped! May 26 23:01:38 lab-pa sudo[3100]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 26 23:01:38 lab-pa sudo[3100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:38 lab-pa systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 23:01:38 lab-pa systemd[1]: shairport-sync.service: Deactivated successfully. May 26 23:01:38 lab-pa systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 23:01:38 lab-pa qobuz-connect[3042]: 20260526 23:01:38.394 [3042.3042] INFO SampleApp: Stopping Local configuration server May 26 23:01:38 lab-pa systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 26 23:01:38 lab-pa sudo[3083]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 23:01:38 lab-pa sudo[3083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 23:01:38 lab-pa sudo[3083]: pam_unix(sudo:session): session closed for user root May 26 23:01:38 lab-pa volumio[1245]: info: TidalConnect service stoped! May 26 23:01:38 lab-pa systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 23:01:38 lab-pa sudo[3099]: pam_unix(sudo:session): session closed for user root May 26 23:01:38 lab-pa volumio[1245]: info: Shairport-Sync Started May 26 23:01:38 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:38.550+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=6 chunks=1 index=0 tries=11 May 26 23:01:38 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:38.551+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%06 @ 0x18e6e10" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" May 26 23:01:38 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:38.552+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" state=STATUS_PAUSED positionMs=204557 volume=100 May 26 23:01:38 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:38.554+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" id="mnt/NAS/MUSIK/_Jens_CD/Dire Straits/On Every Street/01 Calling Elvis.m4a" title="Calling Elvis" May 26 23:01:38 lab-pa volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 26 23:01:38 lab-pa volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 26 23:01:38 lab-pa sudo[3117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 26 23:01:38 lab-pa sudo[3117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:38 lab-pa systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 26 23:01:38 lab-pa sudo[3117]: pam_unix(sudo:session): session closed for user root May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 26 23:01:39 lab-pa volumio[1245]: info: CoreStateMachine::getcurrentVolume May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::volumioRetrievevolume May 26 23:01:39 lab-pa volumio[1245]: info: CoreStateMachine::pushState May 26 23:01:39 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPushState May 26 23:01:39 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output update for this device May 26 23:01:39 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:39 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:39 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:39.112+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" state=STATUS_PAUSED positionMs=204557 volume=100 May 26 23:01:39 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:39.113+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" id="mnt/NAS/MUSIK/_Jens_CD/Dire Straits/On Every Street/01 Calling Elvis.m4a" title="Calling Elvis" May 26 23:01:39 lab-pa volumio[1245]: info: Executing endpoint tc_getconfig May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 26 23:01:39 lab-pa vtcs[3134]: STARTING TidalConnect services, version: 1.6.1 May 26 23:01:39 lab-pa vtcs[3134]: STARTED TidalConnect services. May 26 23:01:39 lab-pa volumio[1245]: info: Executing endpoint tc_connect May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 26 23:01:39 lab-pa volumio[1245]: info: Connecting to TidalConnect May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::servicePushState May 26 23:01:39 lab-pa volumio[1245]: info: CoreStateMachine::pushState May 26 23:01:39 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPushState May 26 23:01:39 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output update for this device May 26 23:01:39 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:39 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:39 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:39 lab-pa volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::servicePushState May 26 23:01:39 lab-pa volumio[1245]: info: CoreStateMachine::pushState May 26 23:01:39 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPushState May 26 23:01:39 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output update for this device May 26 23:01:39 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output May 26 23:01:39 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:39 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:39 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:39 lab-pa volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 26 23:01:39 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:39.539+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" state=STATUS_PAUSED positionMs=204557 volume=100 May 26 23:01:39 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:39.540+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" id="mnt/NAS/MUSIK/_Jens_CD/Dire Straits/On Every Street/01 Calling Elvis.m4a" title="Calling Elvis" May 26 23:01:39 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:39.550+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" state=STATUS_PAUSED positionMs=204557 volume=100 May 26 23:01:39 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:39.554+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" id="mnt/NAS/MUSIK/_Jens_CD/Dire Straits/On Every Street/01 Calling Elvis.m4a" title="Calling Elvis" May 26 23:01:39 lab-pa volumio[1245]: info: TidalConnect service stoped! May 26 23:01:39 lab-pa volumio[1245]: info: TidalConnect service stoped! May 26 23:01:39 lab-pa volumio[1245]: info: TidalConnect service stoped! May 26 23:01:39 lab-pa volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 26 23:01:39 lab-pa volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 26 23:01:39 lab-pa sudo[3159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 26 23:01:39 lab-pa sudo[3159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:40 lab-pa qobuz-connect[3042]: 20260526 23:01:40.024 [3042.3042] INFO SampleApp: shat down connection on UNIX socket May 26 23:01:40 lab-pa volumio[1245]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 26 23:01:40 lab-pa volumio[1245]: info: QobuzConnect: setDeactiveState invoked May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:40 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:40 lab-pa systemd[1]: qobuz-connect.service: Deactivated successfully. May 26 23:01:40 lab-pa systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 26 23:01:40 lab-pa systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 26 23:01:40 lab-pa sudo[3100]: pam_unix(sudo:session): session closed for user root May 26 23:01:40 lab-pa volumio[1245]: info: TidalConnect service stoped! May 26 23:01:40 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:01:40 lab-pa kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1821: inode #5504036: comm nmbd: deleted inode referenced: 5504721 May 26 23:01:40 lab-pa sudo[3159]: pam_unix(sudo:session): session closed for user root May 26 23:01:40 lab-pa volumio[1245]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 26 23:01:40 lab-pa volumio[1245]: info: Preparing to save Alsa Options, stopping services first May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:40 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPause May 26 23:01:40 lab-pa volumio[1245]: info: CoreStateMachine::pause May 26 23:01:40 lab-pa volumio[1245]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:40 lab-pa volumio[1245]: info: Device 3 does not have any Mixer Control Available May 26 23:01:40 lab-pa volumio[1245]: info: QobuzConnect: setDeactiveState invoked May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:40 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:40 lab-pa vtcs[3134]: [2026-05-26 23:01:40.437] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 26 23:01:40 lab-pa vtcs[3134]: [2026-05-26 23:01:40.439] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected May 26 23:01:40 lab-pa sudo[3179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:40 lab-pa sudo[3179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 26 23:01:40 lab-pa volumio[1245]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 26 23:01:40 lab-pa volumio[1245]: info: Disabling external Volume Control May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:01:40 lab-pa volumio[1245]: info: CoreStateMachine::pushState May 26 23:01:40 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPushState May 26 23:01:40 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output update for this device May 26 23:01:40 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:40 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:40 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:40.578+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" state=STATUS_PAUSED positionMs=204557 volume=100 May 26 23:01:40 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:40.579+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" id="mnt/NAS/MUSIK/_Jens_CD/Dire Straits/On Every Street/01 Calling Elvis.m4a" title="Calling Elvis" May 26 23:01:40 lab-pa volumio[1245]: info: Preparing to generate the ALSA configuration file May 26 23:01:40 lab-pa systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 26 23:01:40 lab-pa systemd[1]: vtcs.service: Killing process 3141 (vtcs) with signal SIGKILL. May 26 23:01:40 lab-pa systemd[1]: vtcs.service: Deactivated successfully. May 26 23:01:40 lab-pa systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 26 23:01:40 lab-pa sudo[3179]: pam_unix(sudo:session): session closed for user root May 26 23:01:40 lab-pa sudo[3186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:40 lab-pa sudo[3186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 26 23:01:40 lab-pa volumio[1245]: info: CoreStateMachine::getcurrentVolume May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioRetrievevolume May 26 23:01:40 lab-pa volumio[1245]: info: CoreStateMachine::pushState May 26 23:01:40 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioPushState May 26 23:01:40 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output update for this device May 26 23:01:40 lab-pa volumio[1245]: info: MRS: Pushing multiroomSync output May 26 23:01:40 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:40 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:40 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:40.816+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" state=STATUS_PAUSED positionMs=204557 volume=100 May 26 23:01:40 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:40.817+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" id="mnt/NAS/MUSIK/_Jens_CD/Dire Straits/On Every Street/01 Calling Elvis.m4a" title="Calling Elvis" May 26 23:01:40 lab-pa sudo[3193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 26 23:01:40 lab-pa sudo[3193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:40 lab-pa sudo[3186]: pam_unix(sudo:session): session closed for user root May 26 23:01:41 lab-pa volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 26 23:01:41 lab-pa volumio[1245]: info: Reading ALSA contributions from plugins. May 26 23:01:41 lab-pa sudo[3193]: pam_unix(sudo:session): session closed for user root May 26 23:01:41 lab-pa volumio[1245]: info: Executing endpoint qc_getconfig May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 26 23:01:41 lab-pa sudo[3197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 26 23:01:41 lab-pa sudo[3197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:41 lab-pa qobuz-connect[3161]: 20260526 23:01:41.134 [3161.3161] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 26 23:01:41 lab-pa qobuz-connect[3161]: 20260526 23:01:41.151 [3161.3161] INFO VolumeManager: [0x731f08]: Setting new playback volume: 75 May 26 23:01:41 lab-pa qobuz-connect[3161]: 20260526 23:01:41.151 [3161.3161] INFO VolumeManager: [0x731f08]: Setting new mute state: 0 May 26 23:01:41 lab-pa qobuz-connect[3161]: 20260526 23:01:41.152 [3161.3161] INFO AudioStreamManager: [0x731c60]: Setting new audio download buffer size: 1048576 May 26 23:01:41 lab-pa qobuz-connect[3161]: 20260526 23:01:41.152 [3161.3161] INFO QobuzConnect: [0x7327d0]: Client initialized! May 26 23:01:41 lab-pa qobuz-connect[3161]: 20260526 23:01:41.153 [3161.3161] INFO SampleApp: Starting Avahi advertising, name: lab-pa, service name: _qobuz-connect._tcp May 26 23:01:41 lab-pa volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 26 23:01:41 lab-pa volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 26 23:01:41 lab-pa systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 26 23:01:41 lab-pa qobuz-connect[3161]: 20260526 23:01:41.224 [3161.3161] INFO LocalConfigManager: [0x731988]: Starting Local Configuration server May 26 23:01:41 lab-pa qobuz-connect[3161]: 20260526 23:01:41.224 [3161.3161] INFO SampleApp: Starting Local configuration server May 26 23:01:41 lab-pa qobuz-connect[3161]: 20260526 23:01:41.234 [3161.3161] INFO SampleApp: Stopping Local configuration server May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 23:01:41 lab-pa volumio[1245]: info: Getting Alsa Cards List without I2S DAC May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 23:01:41 lab-pa volumio[1245]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 26 23:01:41 lab-pa volumio[1245]: info: QobuzConnect: QOBUZ Connect daemon connected May 26 23:01:41 lab-pa volumio[1245]: info: TidalConnect service stoped! May 26 23:01:41 lab-pa volumio[1245]: info: TidalConnect service stoped! May 26 23:01:41 lab-pa volumio[1245]: info: Asound.conf file unchanged, so no further update is needed May 26 23:01:41 lab-pa volumio[1245]: info: Output device has changed, restarting MPD May 26 23:01:41 lab-pa sudo[3224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 26 23:01:41 lab-pa sudo[3224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:41 lab-pa volumio[1245]: info: Output device has changed, restarting Shairport Sync May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:41 lab-pa sudo[3230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 23:01:41 lab-pa sudo[3230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:41 lab-pa sudo[3230]: pam_unix(sudo:session): session closed for user root May 26 23:01:41 lab-pa sudo[3233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 23:01:41 lab-pa sudo[3233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:41 lab-pa volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 23:01:41 lab-pa volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:41 lab-pa volumio[1245]: info: QobuzConnect: setDeactiveState invoked May 26 23:01:41 lab-pa volumio[1245]: info: CoreCommandRouter::volumioGetState May 26 23:01:41 lab-pa volumio[1245]: info: CorePlayQueue::getTrack 0 May 26 23:01:41 lab-pa systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 26 23:01:41 lab-pa sudo[3224]: pam_unix(sudo:session): session closed for user root May 26 23:01:41 lab-pa sudo[3245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:41 lab-pa sudo[3245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:41 lab-pa sudo[3249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 23:01:42 lab-pa sudo[3249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:42 lab-pa systemd[1]: mpd.service: Deactivated successfully. May 26 23:01:42 lab-pa systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 23:01:42 lab-pa systemd[1]: mpd.service: Consumed 3.685s CPU time. May 26 23:01:42 lab-pa systemd[1]: mpd.socket: Deactivated successfully. May 26 23:01:42 lab-pa systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 23:01:42 lab-pa systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 23:01:42 lab-pa volumio[1245]: info: MPD Permissions set May 26 23:01:42 lab-pa volumio[1245]: info: TidalConnect service started! May 26 23:01:42 lab-pa volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 26 23:01:42 lab-pa volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:42 lab-pa systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:42 lab-pa sudo[3256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 26 23:01:42 lab-pa systemd[1]: Starting mpd.service - Music Player Daemon... May 26 23:01:42 lab-pa sudo[3256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 23:01:42 lab-pa sudo[3259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 26 23:01:42 lab-pa sudo[3259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:42 lab-pa volumio[1245]: info: Starting Shairport Sync May 26 23:01:42 lab-pa sudo[3269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 23:01:42 lab-pa sudo[3269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:42 lab-pa sudo[3256]: pam_unix(sudo:session): session closed for user root May 26 23:01:42 lab-pa volumio[1245]: info: Executing endpoint tc_getconfig May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 26 23:01:42 lab-pa sudo[3249]: pam_unix(sudo:session): session closed for user root May 26 23:01:42 lab-pa sudo[3245]: pam_unix(sudo:session): session closed for user root May 26 23:01:42 lab-pa vtcs[3243]: STARTING TidalConnect services, version: 1.6.1 May 26 23:01:42 lab-pa vtcs[3243]: STARTED TidalConnect services. May 26 23:01:42 lab-pa systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 23:01:42 lab-pa sudo[3259]: pam_unix(sudo:session): session closed for user root May 26 23:01:42 lab-pa volumio[1245]: info: Executing endpoint tc_connect May 26 23:01:42 lab-pa volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 26 23:01:42 lab-pa volumio[1245]: info: Connecting to TidalConnect May 26 23:01:42 lab-pa systemd[1]: shairport-sync.service: Deactivated successfully. May 26 23:01:42 lab-pa systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 23:01:42 lab-pa systemd[1]: shairport-sync.service: Consumed 1.622s CPU time. May 26 23:01:42 lab-pa volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 23:01:42 lab-pa sudo[3271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 26 23:01:42 lab-pa sudo[3271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 23:01:42 lab-pa volumio[1245]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 May 26 23:01:42 lab-pa volumio[1245]: Job for vtcs.service canceled. May 26 23:01:42 lab-pa volumio[1245]: at ChildProcess.exithandler (node:child_process:421:12) May 26 23:01:42 lab-pa volumio[1245]: at ChildProcess.emit (node:events:514:28) May 26 23:01:42 lab-pa volumio[1245]: at maybeClose (node:internal/child_process:1105:16) May 26 23:01:42 lab-pa volumio[1245]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { May 26 23:01:42 lab-pa volumio[1245]: code: 1, May 26 23:01:42 lab-pa volumio[1245]: killed: false, May 26 23:01:42 lab-pa volumio[1245]: signal: null, May 26 23:01:42 lab-pa volumio[1245]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', May 26 23:01:42 lab-pa volumio[1245]: stdout: '', May 26 23:01:42 lab-pa volumio[1245]: stderr: 'Job for vtcs.service canceled.\n' May 26 23:01:42 lab-pa volumio[1245]: } May 26 23:01:42 lab-pa volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 23:01:42 lab-pa systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 23:01:42 lab-pa sudo[3269]: pam_unix(sudo:session): session closed for user root May 26 23:01:42 lab-pa sudo[3261]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 23:01:42 lab-pa sudo[3261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 23:01:42 lab-pa sudo[3261]: pam_unix(sudo:session): session closed for user root May 26 23:01:42 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:42.770+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=6 chunks=1 index=0 tries=11 May 26 23:01:42 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:42.774+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%06 @ 0x18e6e10" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" May 26 23:01:42 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:42.774+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" state=STATUS_PAUSED positionMs=204557 volume=100 May 26 23:01:42 lab-pa volumio5-onboarding[1568]: time=2026-05-26T23:01:42.775+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.51:56494,00:00:00:00:00:00%08 @ 0x18e6f30" id="mnt/NAS/MUSIK/_Jens_CD/Dire Straits/On Every Street/01 Calling Elvis.m4a" title="Calling Elvis" May 26 23:01:43 lab-pa qobuz-connect[3161]: 20260526 23:01:43.239 [3161.3161] INFO SampleApp: shat down connection on UNIX socket May 26 23:01:43 lab-pa systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 26 23:01:43 lab-pa systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 26 23:01:43 lab-pa systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 26 23:01:43 lab-pa systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 26 23:01:43 lab-pa sudo[3271]: pam_unix(sudo:session): session closed for user root May 26 23:01:43 lab-pa sudo[3197]: pam_unix(sudo:session): session closed for user root May 26 23:01:44 lab-pa sudo[3312]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 23:00' May 26 23:01:44 lab-pa sudo[3312]: 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"