May 30 20:20:03 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 30 20:20:07 volumio go-librespot[2251]: time="2026-05-30T20:20:07Z" level=trace msg="sent dealer ping" May 30 20:20:07 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 20:20:07 volumio volumio[1450]: info: Received Get System Info May 30 20:20:07 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:20:07 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:20:07 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:20:07 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:07 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:07 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:20:07 volumio go-librespot[2251]: time="2026-05-30T20:20:07Z" level=trace msg="received dealer pong" May 30 20:20:09 volumio sudo[7685]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 20:20:09 volumio sudo[7685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:20:09 volumio sudo[7685]: pam_unix(sudo:session): session closed for user root May 30 20:20:09 volumio sudo[7687]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 20:20:09 volumio sudo[7687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:20:09 volumio sudo[7687]: pam_unix(sudo:session): session closed for user root May 30 20:20:09 volumio volumio[1450]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.224 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: Received Get System Info May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:20:09 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: Received Get System Info May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:20:09 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetVisibleSources May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 20:20:09 volumio volumio[1450]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 30 20:20:09 volumio volumio[1450]: info: Received Get System Info May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:20:09 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: Listing playlists May 30 20:20:09 volumio volumio[1450]: info: Received Get System Info May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:20:09 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: Received Get System Info May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:20:09 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:09 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:09 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 30 20:20:10 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:20:10 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:20:12 volumio volumio[1450]: info: Executing endpoint metavolumio May 30 20:20:12 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 20:20:12 volumio volumio[1450]: info: Executing endpoint metavolumio May 30 20:20:12 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 30 20:20:13 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 30 20:20:19 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 30 20:20:37 volumio go-librespot[2251]: time="2026-05-30T20:20:37Z" level=trace msg="sent dealer ping" May 30 20:20:37 volumio go-librespot[2251]: time="2026-05-30T20:20:37Z" level=trace msg="received dealer pong" May 30 20:20:41 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput May 30 20:20:41 volumio volumio[1450]: info: MRS: Starting browser stream May 30 20:20:41 volumio volumio[1450]: info: MRS: Setting this device as Streaming Server May 30 20:20:41 volumio volumio[1450]: info: May 30 20:20:41 volumio volumio[1450]: [1780172441077] ---------------------------- MRS: Setting Streaming Server May 30 20:20:41 volumio volumio[1450]: info: Enabled audio output: browserPlayback May 30 20:20:41 volumio volumio[1450]: info: MRS: enable multiroom server output May 30 20:20:41 volumio volumio[1450]: info: MRS: Set multiroom target PCM to volumioMultiRoom May 30 20:20:41 volumio volumio[1450]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom May 30 20:20:41 volumio volumio[1450]: info: MRS: Set multiroom target PCM to volumioLocalPlayback May 30 20:20:41 volumio volumio[1450]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback May 30 20:20:41 volumio volumio[1450]: info: MRS: STARTING volumioStreaming May 30 20:20:41 volumio sudo[7889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 30 20:20:41 volumio sudo[7889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:20:41 volumio sudo[7889]: pam_unix(sudo:session): session closed for user root May 30 20:20:41 volumio sudo[7891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 30 20:20:41 volumio sudo[7891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:20:41 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. May 30 20:20:41 volumio sudo[7891]: pam_unix(sudo:session): session closed for user root May 30 20:20:41 volumio volumio[1450]: info: MRS: volumioStreaming STARTED May 30 20:20:41 volumio sudo[7895]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 30 20:20:41 volumio sudo[7895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:20:41 volumio sudo[7895]: pam_unix(sudo:session): session closed for user root May 30 20:20:51 volumio ntpd[1233]: PROTO: 193.6.222.20 unlink local addr 192.168.1.107 -> May 30 20:20:58 volumio ntpd[1233]: PROTO: 80.249.164.129 unlink local addr 192.168.1.107 -> May 30 20:21:07 volumio go-librespot[2251]: time="2026-05-30T20:21:07Z" level=trace msg="sent dealer ping" May 30 20:21:07 volumio go-librespot[2251]: time="2026-05-30T20:21:07Z" level=trace msg="received dealer pong" May 30 20:21:14 volumio rfkill[8121]: unblock set for type bluetooth May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci1/dev_04_A2_22_01_EB_10/sep1, ...) May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1362: Adding new Stream End-Point: 04:A2:22:01:EB:10: SNK: SBC May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci1/dev_04_A2_22_01_EB_10/sep2, ...) May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1362: Adding new Stream End-Point: 04:A2:22:01:EB:10: SNK: AAC May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci1/dev_04_A2_22_01_EB_10/sep3, ...) May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1362: Adding new Stream End-Point: 04:A2:22:01:EB:10: SNK: SBC May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci1/dev_04_A2_22_01_EB_10/sep4, ...) May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1362: Adding new Stream End-Point: 04:A2:22:01:EB:10: SNK: AAC May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci1/dev_04_A2_22_01_EB_10/sep5, ...) May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1362: Adding new Stream End-Point: 04:A2:22:01:EB:10: SNK: SBC May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci1/dev_04_A2_22_01_EB_10/sep6, ...) May 30 20:21:14 volumio bluealsa[1138]: bluez.c:1362: Adding new Stream End-Point: 04:A2:22:01:EB:10: SNK: AAC May 30 20:21:14 volumio kernel: input: RX-V485 01EB0D Bluetooth (AVRCP) as /devices/virtual/input/input10 May 30 20:21:14 volumio systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon... May 30 20:21:14 volumio bluetoothd[1069]: profiles/audio/avctp.c:avctp_connect_browsing_cb() Browsing: connect to 04:A2:22:01:EB:10: Connection reset by peer (104) May 30 20:21:15 volumio systemd[1]: triggerhappy.service: Deactivated successfully. May 30 20:21:15 volumio systemd[1]: Stopped triggerhappy.service - triggerhappy global hotkey daemon. May 30 20:21:15 volumio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... May 30 20:21:15 volumio thd[8127]: Unable to parse trigger line: May 30 20:21:15 volumio thd[8127]: Unable to parse trigger line: May 30 20:21:15 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. May 30 20:21:15 volumio systemd-logind[1074]: Watching system buttons on /dev/input/event10 (RX-V485 01EB0D Bluetooth (AVRCP)) May 30 20:21:15 volumio bluealsa[1138]: ../src/dbus.c:47: Called: org.bluez.MediaEndpoint1.SelectConfiguration() on /org/bluez/hci1/A2DP/SBC/source/2 May 30 20:21:15 volumio bluealsa[1138]: bluez.c:429: A2DP peer capabilities blob [len=4]: ffff0259 May 30 20:21:15 volumio bluealsa[1138]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci1/dev_04_A2_22_01_EB_10/sep1/fd0, ...) May 30 20:21:15 volumio bluealsa[1138]: ../src/dbus.c:47: Called: org.bluez.MediaEndpoint1.SetConfiguration() on /org/bluez/hci1/A2DP/SBC/source/2 May 30 20:21:15 volumio bluealsa[1138]: ../src/a2dp-sbc.c:536: SBC: Selected bit-pool range: [2, 89] May 30 20:21:15 volumio bluealsa[1138]: ../src/storage.c:123: Loading storage: /var/lib/bluealsa/04:A2:22:01:EB:10 May 30 20:21:15 volumio bluealsa[1138]: bluez.c:572: A2DP Source (SBC) configured for device 04:A2:22:01:EB:10 May 30 20:21:15 volumio bluealsa[1138]: bluez.c:575: A2DP selected configuration blob [len=4]: 11150259 May 30 20:21:15 volumio bluealsa[1138]: bluez.c:577: PCM configuration: channels: 2, sampling: 48000 May 30 20:21:15 volumio bluealsa[1138]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci1/A2DP/SBC/source/3 May 30 20:21:15 volumio bluetoothd[1069]: Endpoint registered: sender=:1.6 path=/org/bluez/hci1/A2DP/SBC/source/3 May 30 20:21:15 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 04:A2:22:01:EB:10, rawVolume = 64 May 30 20:21:15 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Initial volume cap applied for 04:A2:22:01:EB:10: 50 (cap 75%) May 30 20:21:15 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Received volume: raw = 64, scaled = 50 May 30 20:21:15 volumio volumio[1450]: info: VolumeController::SetAlsaVolume50 May 30 20:21:15 volumio volumio[1450]: ------------------------------------ BT MESSAGE: [WARN] pushMultiRoomVolume is not available May 30 20:21:15 volumio volumio[1450]: info: VolumeController::SetAlsaVolume50 May 30 20:21:15 volumio volumio[1450]: info: CoreStateMachine::pushState May 30 20:21:15 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:15 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 20:21:15 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 30 20:21:15 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 30 20:21:15 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 30 20:21:15 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:15 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:15 volumio volumio[1450]: info: CoreStateMachine::pushState May 30 20:21:15 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:15 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 30 20:21:15 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 30 20:21:15 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 30 20:21:15 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:15 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:15 volumio volumio5-onboarding[2592]: time=2026-05-30T20:21:15.484Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.164:51030 @ 0xc00028f3e0" state=STATUS_PAUSED positionMs=306902 volume=50 May 30 20:21:15 volumio volumio5-onboarding[2592]: time=2026-05-30T20:21:15.484Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.164:51030 @ 0xc00028f3e0" id=http://streams.80s80s.de/love/mp3-192/volumio title="Billy Ocean - Suddenly" May 30 20:21:15 volumio volumio5-onboarding[2592]: time=2026-05-30T20:21:15.484Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.164:51030 @ 0xc00028f3e0" state=STATUS_PAUSED positionMs=306902 volume=50 May 30 20:21:15 volumio volumio5-onboarding[2592]: time=2026-05-30T20:21:15.484Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.164:51030 @ 0xc00028f3e0" id=http://streams.80s80s.de/love/mp3-192/volumio title="Billy Ocean - Suddenly" May 30 20:21:15 volumio volumio[1450]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 May 30 20:21:15 volumio volumio[1450]: SPOTIFY: SPOTIFY VOLUME 32 May 30 20:21:15 volumio volumio[1450]: SPOTIFY: VOLUMIO VOLUME 50 May 30 20:21:15 volumio volumio[1450]: SPOTIFY: DELTA VOLUME ENOUGH: true May 30 20:21:15 volumio volumio[1450]: info: Setting Spotify Volume from Volumio: 50 May 30 20:21:15 volumio volumio[1450]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 May 30 20:21:15 volumio volumio-bt-out.sh[1253]: BT eszköz találva: 04:A2:22:01:EB:10 May 30 20:21:15 volumio volumio-bt-out.sh[8138]: bluealsa-aplay: [8138] D: aplay.c:1320: Starting main loop May 30 20:21:16 volumio volumio[1450]: SPOTIFY: SETTING SPOTIFY VOLUME 50 May 30 20:21:16 volumio volumio[1450]: info: Sending Spotify command with payload to local API: /player/volume May 30 20:21:16 volumio go-librespot[2251]: time="2026-05-30T20:21:16Z" level=debug msg="update volume requested to 32767/65535" May 30 20:21:17 volumio go-librespot[2251]: time="2026-05-30T20:21:17Z" level=debug msg="put connect state because VOLUME_CHANGED" May 30 20:21:17 volumio go-librespot[2251]: time="2026-05-30T20:21:17Z" level=trace msg="emitting websocket event: volume" May 30 20:21:17 volumio volumio[1450]: SPOTIFY: received: {"type":"volume","data":{"value":50,"max":100}} May 30 20:21:17 volumio volumio[1450]: SPOTIFY: RECEIVED SPOTIFY VOLUME 50 May 30 20:21:18 volumio volumio[1450]: info: MRS: STARTING volumioStreaming May 30 20:21:18 volumio sudo[8147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 30 20:21:18 volumio sudo[8147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:18 volumio sudo[8147]: pam_unix(sudo:session): session closed for user root May 30 20:21:18 volumio sudo[8149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 30 20:21:18 volumio sudo[8149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:18 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... May 30 20:21:18 volumio systemd[1]: volumioStreaming.service: Killing process 7897 (ffmpeg) with signal SIGKILL. May 30 20:21:18 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. May 30 20:21:18 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. May 30 20:21:18 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. May 30 20:21:18 volumio sudo[8149]: pam_unix(sudo:session): session closed for user root May 30 20:21:18 volumio volumio[1450]: info: MRS: volumioStreaming STARTED May 30 20:21:18 volumio sudo[8156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 30 20:21:18 volumio sudo[8156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:18 volumio sudo[8156]: pam_unix(sudo:session): session closed for user root May 30 20:21:19 volumio bluealsa[1138]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume May 30 20:21:19 volumio bluealsa[1138]: bluez.c:1498: Skipping A2DP volume update: 63 May 30 20:21:19 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Ignoring source transport volume for 04:A2:22:01:EB:10: 63 May 30 20:21:20 volumio bluealsa[1138]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume May 30 20:21:20 volumio bluealsa[1138]: bluez.c:1498: Skipping A2DP volume update: 62 May 30 20:21:20 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Ignoring source transport volume for 04:A2:22:01:EB:10: 62 May 30 20:21:20 volumio bluealsa[1138]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume May 30 20:21:20 volumio bluealsa[1138]: bluez.c:1498: Skipping A2DP volume update: 60 May 30 20:21:20 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Ignoring source transport volume for 04:A2:22:01:EB:10: 60 May 30 20:21:21 volumio bluealsa[1138]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume May 30 20:21:21 volumio bluealsa[1138]: bluez.c:1498: Skipping A2DP volume update: 59 May 30 20:21:21 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Ignoring source transport volume for 04:A2:22:01:EB:10: 59 May 30 20:21:21 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:21 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:21 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 30 20:21:21 volumio volumio[1450]: info: CoreCommandRouter::volumioPlay May 30 20:21:21 volumio volumio[1450]: info: CoreStateMachine::play index undefined May 30 20:21:21 volumio volumio[1450]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 20:21:21 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:21 volumio volumio[1450]: info: CoreStateMachine::startPlaybackTimer May 30 20:21:21 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:21 volumio volumio[1450]: info: CoreCommandRouter::volumioGetVisibleSources May 30 20:21:21 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:21:21 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 30 20:21:21 volumio volumio[1450]: verbose: MPD COMMAND [object Object] May 30 20:21:21 volumio volumio[1450]: verbose: MPD COMMAND [object Object] May 30 20:21:23 volumio bluealsa[1138]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume May 30 20:21:23 volumio bluealsa[1138]: bluez.c:1498: Skipping A2DP volume update: 64 May 30 20:21:23 volumio volumio[1450]: ------------------------------------ BT MESSAGE: Ignoring source transport volume for 04:A2:22:01:EB:10: 64 May 30 20:21:25 volumio sudo[8196]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 20:21:25 volumio sudo[8196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:25 volumio sudo[8196]: pam_unix(sudo:session): session closed for user root May 30 20:21:25 volumio sudo[8198]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 20:21:25 volumio sudo[8198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:25 volumio sudo[8198]: pam_unix(sudo:session): session closed for user root May 30 20:21:26 volumio volumio[1450]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.224 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::volumioGetVisibleSources May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:26 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 20:21:26 volumio volumio[1450]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 30 20:21:26 volumio volumio[1450]: info: Received Get System Info May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:21:26 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:26 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:26 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:26 volumio volumio[1450]: info: Listing playlists May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 30 20:21:26 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 30 20:21:27 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 30 20:21:27 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 20:21:27 volumio volumio[1450]: info: Received Get System Info May 30 20:21:27 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:21:27 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:21:27 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:21:27 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:27 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:27 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:21:28 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 20:21:28 volumio volumio[1450]: info: Received Get System Info May 30 20:21:28 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:21:28 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:21:28 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:21:28 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:28 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:28 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:21:31 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:31 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:31 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 30 20:21:31 volumio volumio[1450]: info: CoreCommandRouter::volumioPlay May 30 20:21:31 volumio volumio[1450]: info: CoreStateMachine::play index undefined May 30 20:21:31 volumio volumio[1450]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 20:21:31 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:31 volumio volumio[1450]: info: CoreStateMachine::startPlaybackTimer May 30 20:21:31 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:31 volumio volumio[1450]: info: CoreCommandRouter::volumioGetVisibleSources May 30 20:21:31 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 20:21:31 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 30 20:21:31 volumio volumio[1450]: verbose: MPD COMMAND [object Object] May 30 20:21:31 volumio volumio[1450]: verbose: MPD COMMAND [object Object] May 30 20:21:36 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 30 20:21:36 volumio go-librespot[2251]: time="2026-05-30T20:21:36Z" level=trace msg="received accesspoint ping" May 30 20:21:36 volumio go-librespot[2251]: time="2026-05-30T20:21:36Z" level=trace msg="received accesspoint pong ack" May 30 20:21:37 volumio go-librespot[2251]: time="2026-05-30T20:21:37Z" level=trace msg="sent dealer ping" May 30 20:21:37 volumio go-librespot[2251]: time="2026-05-30T20:21:37Z" level=trace msg="received dealer pong" May 30 20:21:38 volumio ntpd[1233]: PROTO: 195.111.92.55 unlink local addr 192.168.1.107 -> May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 20:21:44 volumio volumio[1450]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 30 20:21:44 volumio volumio[1450]: info: Received Get System Version May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 20:21:44 volumio volumio[1450]: info: Received Get System Info May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 20:21:44 volumio volumio[1450]: info: Discovery: Getting this device information May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:44 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 20:21:44 volumio volumio[1450]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 30 20:21:44 volumio volumio[1450]: info: Preparing to save Alsa Options, stopping services first May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:44 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::volumioPause May 30 20:21:44 volumio volumio[1450]: info: CoreStateMachine::pause May 30 20:21:44 volumio volumio[1450]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:21:44 volumio volumio[1450]: info: Found match in Cards Database: setting mixer for card Analog Out May 30 20:21:44 volumio volumio[1450]: info: QobuzConnect: setDeactiveState invoked May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:44 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:44 volumio vtcs[7498]: [2026-05-30 20:21:44.308] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected May 30 20:21:44 volumio vtcs[7498]: [2026-05-30 20:21:44.308] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 30 20:21:44 volumio sudo[8301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:44 volumio sudo[8301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio volumio[1450]: info: Setting mixer Master for card Analog Out May 30 20:21:44 volumio volumio[1450]: info: QobuzConnect: setDeactiveState invoked May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:44 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:44 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 30 20:21:44 volumio systemd[1]: vtcs.service: Killing process 7505 (vtcs) with signal SIGKILL. May 30 20:21:44 volumio systemd[1]: vtcs.service: Deactivated successfully. May 30 20:21:44 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 30 20:21:44 volumio sudo[8307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:44 volumio sudo[8307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio sudo[8301]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 30 20:21:44 volumio volumio[1450]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 30 20:21:44 volumio volumio[1450]: info: Disabling external Volume Control May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 20:21:44 volumio volumio[1450]: info: Preparing to generate the ALSA configuration file May 30 20:21:44 volumio sudo[8307]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio sudo[8316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:44 volumio sudo[8316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio sudo[8318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:44 volumio sudo[8318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:44 volumio sudo[8326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 30 20:21:44 volumio sudo[8316]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio sudo[8326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 30 20:21:44 volumio volumio[1450]: info: Reading ALSA contributions from plugins. May 30 20:21:44 volumio sudo[8318]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio sudo[8334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 30 20:21:44 volumio sudo[8326]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio sudo[8334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio sudo[8339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 30 20:21:44 volumio sudo[8339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio sudo[8334]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:21:44 volumio sudo[8343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 30 20:21:44 volumio sudo[8343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 30 20:21:44 volumio qobuz-connect[7431]: 20260530 20:21:44.357 [7431.7431] INFO SampleApp: Stopping Local configuration server May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 30 20:21:44 volumio volumio[1450]: info: VolumeController:: Volume=51 Mute =false May 30 20:21:44 volumio volumio[1450]: info: CoreStateMachine::pushState May 30 20:21:44 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 30 20:21:44 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 30 20:21:44 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:44 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:44 volumio volumio5-onboarding[2592]: time=2026-05-30T20:21:44.371Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.164:51030 @ 0xc00028f3e0" state=STATUS_PAUSED positionMs=335330 volume=51 May 30 20:21:44 volumio volumio5-onboarding[2592]: time=2026-05-30T20:21:44.371Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.164:51030 @ 0xc00028f3e0" id=http://streams.80s80s.de/love/mp3-192/volumio title="Billy Ocean - Suddenly" May 30 20:21:44 volumio volumio[1450]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 30 20:21:44 volumio volumio[1450]: SPOTIFY: SPOTIFY VOLUME 50 May 30 20:21:44 volumio volumio[1450]: SPOTIFY: VOLUMIO VOLUME 51 May 30 20:21:44 volumio volumio[1450]: SPOTIFY: DELTA VOLUME ENOUGH: false May 30 20:21:44 volumio volumio[1450]: info: Asound.conf file unchanged, so no further update is needed May 30 20:21:44 volumio volumio[1450]: info: Output device has changed, restarting MPD May 30 20:21:44 volumio volumio[1450]: info: Output device has changed, restarting Shairport Sync May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:44 volumio sudo[8350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 20:21:44 volumio sudo[8350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio sudo[8350]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio sudo[8352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 20:21:44 volumio sudo[8352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio volumio[1450]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 20:21:44 volumio volumio[1450]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: QobuzConnect: setDeactiveState invoked May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:44 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:44 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 30 20:21:44 volumio sudo[8362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:44 volumio sudo[8362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio sudo[8364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:44 volumio sudo[8364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:44 volumio systemd[1]: mpd.service: Deactivated successfully. May 30 20:21:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 20:21:44 volumio systemd[1]: mpd.socket: Deactivated successfully. May 30 20:21:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 20:21:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 20:21:44 volumio volumio[1450]: info: MPD Permissions set May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio sudo[8374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 30 20:21:44 volumio sudo[8374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 30 20:21:44 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:44 volumio volumio[1450]: info: Starting Shairport Sync May 30 20:21:44 volumio sudo[8385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 20:21:44 volumio sudo[8385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 20:21:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 30 20:21:44 volumio sudo[8374]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 20:21:44 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 30 20:21:44 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:21:44 volumio systemd[1]: shairport-sync.service: Consumed 2.024s CPU time. May 30 20:21:44 volumio sudo[8389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 30 20:21:44 volumio sudo[8389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:44 volumio sudo[8362]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio sudo[8364]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio sudo[8388]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 20:21:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:21:44 volumio sudo[8388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:21:44 volumio sudo[8385]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio sudo[8388]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio volumio[1450]: info: Shairport-Sync Started May 30 20:21:44 volumio mpd[8395]: 2026-05-30T20:21:44 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 30 20:21:44 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 30 20:21:44 volumio sudo[8352]: pam_unix(sudo:session): session closed for user root May 30 20:21:44 volumio volumio[1450]: error: updateQueue error: null May 30 20:21:45 volumio qobuz-connect[7431]: 20260530 20:21:45.184 [7431.7431] INFO SampleApp: shat down connection on UNIX socket May 30 20:21:45 volumio volumio[1450]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 30 20:21:45 volumio volumio[1450]: info: QobuzConnect: setDeactiveState invoked May 30 20:21:45 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:45 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:45 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. May 30 20:21:45 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 30 20:21:45 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 30 20:21:45 volumio sudo[8343]: pam_unix(sudo:session): session closed for user root May 30 20:21:45 volumio sudo[8389]: pam_unix(sudo:session): session closed for user root May 30 20:21:45 volumio sudo[8339]: pam_unix(sudo:session): session closed for user root May 30 20:21:45 volumio volumio[1450]: info: Executing endpoint qc_getconfig May 30 20:21:45 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.217 [8413.8413] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 30 20:21:45 volumio volumio[1450]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 30 20:21:45 volumio volumio[1450]: info: QobuzConnect: QOBUZ Connect daemon connected May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.218 [8413.8413] INFO VolumeManager: [0x55b744440560]: Setting new playback volume: 75 May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.218 [8413.8413] INFO VolumeManager: [0x55b744440560]: Setting new mute state: 0 May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.218 [8413.8413] INFO AudioStreamManager: [0x55b7444400c0]: Setting new audio download buffer size: 1048576 May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.218 [8413.8413] INFO QobuzConnect: [0x55b744441570]: Client initialized! May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.218 [8413.8413] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.220 [8413.8413] INFO LocalConfigManager: [0x55b74443fba0]: Starting Local Configuration server May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.220 [8413.8413] INFO SampleApp: Starting Local configuration server May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.220 [8413.8413] INFO SampleApp: Connected to UNIX socket client 0x55b744416bb0 May 30 20:21:45 volumio qobuz-connect[8413]: 20260530 20:21:45.367 [8413.8413] INFO SampleApp: Playback volume changed: 75 May 30 20:21:45 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:45 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:47 volumio volumio[1450]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 30 20:21:47 volumio volumio[1450]: info: Preparing to save Alsa Options, stopping services first May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:47 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::volumioPause May 30 20:21:47 volumio volumio[1450]: info: CoreStateMachine::pause May 30 20:21:47 volumio volumio[1450]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:21:47 volumio volumio[1450]: info: Found match in Cards Database: setting mixer for card Analog Out May 30 20:21:47 volumio volumio[1450]: info: QobuzConnect: setDeactiveState invoked May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:47 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:47 volumio sudo[8432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:47 volumio sudo[8432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio volumio[1450]: info: Setting mixer Master for card Analog Out May 30 20:21:47 volumio volumio[1450]: info: QobuzConnect: setDeactiveState invoked May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:47 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:47 volumio sudo[8438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:47 volumio sudo[8438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 30 20:21:47 volumio volumio[1450]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 30 20:21:47 volumio volumio[1450]: info: Disabling external Volume Control May 30 20:21:47 volumio sudo[8432]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 20:21:47 volumio volumio[1450]: info: Preparing to generate the ALSA configuration file May 30 20:21:47 volumio sudo[8438]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:47 volumio sudo[8447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio sudo[8449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:47 volumio sudo[8449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:47 volumio sudo[8457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 30 20:21:47 volumio sudo[8457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 30 20:21:47 volumio volumio[1450]: info: Reading ALSA contributions from plugins. May 30 20:21:47 volumio sudo[8447]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 30 20:21:47 volumio sudo[8464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio sudo[8449]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8457]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8464]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 30 20:21:47 volumio sudo[8469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 30 20:21:47 volumio sudo[8472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 20:21:47 volumio sudo[8472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 30 20:21:47 volumio qobuz-connect[8413]: 20260530 20:21:47.325 [8413.8413] INFO SampleApp: Stopping Local configuration server May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 30 20:21:47 volumio volumio[1450]: info: TidalConnect service stoped! May 30 20:21:47 volumio volumio[1450]: info: VolumeController:: Volume=51 Mute =false May 30 20:21:47 volumio volumio[1450]: info: CoreStateMachine::pushState May 30 20:21:47 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::volumioPushState May 30 20:21:47 volumio volumio[1450]: info: MRS: Pushing multiroomSync output update for this device May 30 20:21:47 volumio volumio[1450]: info: MRS: Pushing multiroomSync output May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:47 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:47 volumio volumio5-onboarding[2592]: time=2026-05-30T20:21:47.335Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.164:51030 @ 0xc00028f3e0" state=STATUS_PAUSED positionMs=338330 volume=51 May 30 20:21:47 volumio volumio5-onboarding[2592]: time=2026-05-30T20:21:47.335Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.164:51030 @ 0xc00028f3e0" id=http://streams.80s80s.de/love/mp3-192/volumio title="Billy Ocean - Suddenly" May 30 20:21:47 volumio volumio[1450]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 May 30 20:21:47 volumio volumio[1450]: SPOTIFY: SPOTIFY VOLUME 50 May 30 20:21:47 volumio volumio[1450]: SPOTIFY: VOLUMIO VOLUME 51 May 30 20:21:47 volumio volumio[1450]: SPOTIFY: DELTA VOLUME ENOUGH: false May 30 20:21:47 volumio volumio[1450]: info: TidalConnect service stoped! May 30 20:21:47 volumio volumio[1450]: info: Asound.conf file unchanged, so no further update is needed May 30 20:21:47 volumio volumio[1450]: info: Output device has changed, restarting MPD May 30 20:21:47 volumio volumio[1450]: info: Output device has changed, restarting Shairport Sync May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:47 volumio sudo[8480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 30 20:21:47 volumio sudo[8480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio sudo[8480]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 30 20:21:47 volumio sudo[8482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio volumio[1450]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 20:21:47 volumio volumio[1450]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: QobuzConnect: setDeactiveState invoked May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::volumioGetState May 30 20:21:47 volumio volumio[1450]: info: CorePlayQueue::getTrack 0 May 30 20:21:47 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 30 20:21:47 volumio sudo[8492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:47 volumio sudo[8492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio systemd[1]: mpd.service: Deactivated successfully. May 30 20:21:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 30 20:21:47 volumio systemd[1]: mpd.socket: Deactivated successfully. May 30 20:21:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 30 20:21:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 30 20:21:47 volumio sudo[8494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 30 20:21:47 volumio sudo[8494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: MPD Permissions set May 30 20:21:47 volumio volumio[1450]: info: TidalConnect service stoped! May 30 20:21:47 volumio sudo[8503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 30 20:21:47 volumio sudo[8503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio volumio[1450]: info: TidalConnect service stoped! May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 30 20:21:47 volumio volumio[1450]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 20:21:47 volumio volumio[1450]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 30 20:21:47 volumio volumio[1450]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 30 20:21:47 volumio volumio[1450]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 30 20:21:47 volumio volumio[1450]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 30 20:21:47 volumio volumio[1450]: info: Starting Shairport Sync May 30 20:21:47 volumio sudo[8530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 20:21:47 volumio sudo[8530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio sudo[8532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 30 20:21:47 volumio sudo[8532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio sudo[8535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 30 20:21:47 volumio sudo[8535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 30 20:21:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 30 20:21:47 volumio sudo[8503]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8492]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8494]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 30 20:21:47 volumio sudo[8539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 20:21:47 volumio volumio[1450]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 20:21:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 30 20:21:47 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 30 20:21:47 volumio sudo[8535]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8532]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio volumio[1450]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 May 30 20:21:47 volumio volumio[1450]: Job for vtcs.service canceled. May 30 20:21:47 volumio volumio[1450]: at ChildProcess.exithandler (node:child_process:421:12) May 30 20:21:47 volumio volumio[1450]: at ChildProcess.emit (node:events:514:28) May 30 20:21:47 volumio volumio[1450]: at maybeClose (node:internal/child_process:1105:16) May 30 20:21:47 volumio volumio[1450]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { May 30 20:21:47 volumio volumio[1450]: code: 1, May 30 20:21:47 volumio volumio[1450]: killed: false, May 30 20:21:47 volumio volumio[1450]: signal: null, May 30 20:21:47 volumio volumio[1450]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', May 30 20:21:47 volumio volumio[1450]: stdout: '', May 30 20:21:47 volumio volumio[1450]: stderr: 'Job for vtcs.service canceled.\n' May 30 20:21:47 volumio volumio[1450]: } May 30 20:21:47 volumio volumio[1450]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 20:21:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 30 20:21:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:21:47 volumio systemd[1]: shairport-sync.service: Consumed 1.521s CPU time. May 30 20:21:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 30 20:21:47 volumio sudo[8530]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8538]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 30 20:21:47 volumio sudo[8538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 30 20:21:47 volumio sudo[8538]: pam_unix(sudo:session): session closed for user root May 30 20:21:47 volumio sudo[8576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 20:20' May 30 20:21:47 volumio sudo[8576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"