May 31 18:38:00 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 18:38:00 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 18:38:00 volumio-mackie volumio[1205]: info: Received Get System Info May 31 18:38:00 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:38:00 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:38:00 volumio-mackie volumio[1205]: info: Discovery: Getting this device information May 31 18:38:00 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:00 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:00 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:38:01 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 31 18:38:01 volumio-mackie volumio[1205]: info: MyVolumio login type: Token May 31 18:38:01 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 18:38:01 volumio-mackie volumio[1205]: info: Received Get System Info May 31 18:38:01 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:38:01 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:38:01 volumio-mackie volumio[1205]: info: Discovery: Getting this device information May 31 18:38:01 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:01 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:01 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:38:01 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 18:38:01 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:01.509Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" userId= May 31 18:38:01 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 18:38:01 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 31 18:38:01 volumio-mackie volumio[1205]: info: MyVolumio login type: Token May 31 18:38:02 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 18:38:02 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:02.154Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" userId= May 31 18:38:02 volumio-mackie volumio[1205]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 18:38:03 volumio-mackie volumio[1205]: info: Received Get System Info May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:38:03 volumio-mackie volumio[1205]: info: Discovery: Getting this device information May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:03 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 18:38:03 volumio-mackie volumio[1205]: info: Received Get System Info May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:38:03 volumio-mackie volumio[1205]: info: Discovery: Getting this device information May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:03 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:03 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:38:03 volumio-mackie volumio[1205]: info: MyVolumio token set successfully May 31 18:38:03 volumio-mackie volumio[1205]: info: MYVOLUMIO: Adding device May 31 18:38:03 volumio-mackie volumio[1205]: info: MYVOLUMIO: Evaluating Server May 31 18:38:05 volumio-mackie volumio[1205]: info: MyVolumio Plan changed: premium May 31 18:38:05 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 31 18:38:05 volumio-mackie volumio[1205]: info: Removing browser output: myVolumio user plan is not superstar May 31 18:38:05 volumio-mackie volumio[1205]: info: Removing audio output: May 31 18:38:05 volumio-mackie volumio[1205]: info: MYVOLUMIO: Adding device May 31 18:38:05 volumio-mackie volumio[1205]: info: MYVOLUMIO: Evaluating Server May 31 18:38:05 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 18:38:05 volumio-mackie volumio[1205]: info: Received Get System Info May 31 18:38:05 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:38:05 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:38:05 volumio-mackie volumio[1205]: info: Discovery: Getting this device information May 31 18:38:05 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:05 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:05 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:38:06 volumio-mackie volumio[1205]: info: Setting Geolocation for MyVolumio to us3 May 31 18:38:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse May 31 18:38:06 volumio-mackie volumio[1205]: info: Remote config written successfully May 31 18:38:06 volumio-mackie volumio[1205]: info: Starting Tunnel 1 May 31 18:38:06 volumio-mackie volumio[1205]: info: Starting Tunnel Connection Checker May 31 18:38:06 volumio-mackie volumio[1205]: info: Setting Geolocation for MyVolumio to us3 May 31 18:38:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:06 volumio-mackie volumio[1205]: info: Successfully Added MyVolumio device May 31 18:38:06 volumio-mackie volumio[1205]: info: MYVolumio Device enabled May 31 18:38:06 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 31 18:38:06 volumio-mackie volumio[1205]: info: MyVolumio status changed May 31 18:38:06 volumio-mackie volumio[1205]: info: Streaming services startup May 31 18:38:06 volumio-mackie volumio[1205]: info: Starting Streaming Daemon May 31 18:38:06 volumio-mackie volumio[1205]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' May 31 18:38:06 volumio-mackie sudo[2667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 18:38:06 volumio-mackie sudo[2667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:06 volumio-mackie volumio[1205]: info: Successfully Added MyVolumio device May 31 18:38:06 volumio-mackie sudo[2667]: pam_unix(sudo:session): session closed for user root May 31 18:38:06 volumio-mackie volumio[1205]: error: Cannot start Volumio Streaming Daemon May 31 18:38:06 volumio-mackie volumio[1205]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 18:38:06 volumio-mackie volumio[1205]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 18:38:07 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 31 18:38:08 volumio-mackie volumio[1205]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 31 18:38:08 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 31 18:38:16 volumio-mackie sudo[2688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 31 18:38:16 volumio-mackie sudo[2688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:16 volumio-mackie sudo[2688]: pam_unix(sudo:session): session closed for user root May 31 18:38:16 volumio-mackie volumio[1205]: info: MRS: MultiRoom plugin initialized May 31 18:38:16 volumio-mackie volumio[1205]: info: MRS: STOPPING SNAPCLIENT May 31 18:38:16 volumio-mackie volumio[1205]: info: MRS: Snap server stop May 31 18:38:16 volumio-mackie volumio[1205]: info: MRS: STOPPING volumioStreaming May 31 18:38:16 volumio-mackie sudo[2705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 31 18:38:16 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 31 18:38:16 volumio-mackie sudo[2705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:16 volumio-mackie sudo[2707]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 31 18:38:16 volumio-mackie sudo[2707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:16 volumio-mackie sudo[2709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 31 18:38:17 volumio-mackie sudo[2709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:17 volumio-mackie sudo[2711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 31 18:38:17 volumio-mackie sudo[2711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:17 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 31 18:38:17 volumio-mackie sudo[2711]: pam_unix(sudo:session): session closed for user root May 31 18:38:17 volumio-mackie sudo[2705]: pam_unix(sudo:session): session closed for user root May 31 18:38:17 volumio-mackie sudo[2707]: pam_unix(sudo:session): session closed for user root May 31 18:38:17 volumio-mackie sudo[2709]: pam_unix(sudo:session): session closed for user root May 31 18:38:17 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 31 18:38:17 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 31 18:38:17 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 31 18:38:17 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 31 18:38:17 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 31 18:38:17 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 31 18:38:17 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 31 18:38:18 volumio-mackie volumio[1205]: info: Preparing to generate the ALSA configuration file May 31 18:38:18 volumio-mackie volumio[1205]: info: Updating MyVolumio device info May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:18 volumio-mackie volumio[1205]: info: Updating MyVolumio device info May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetQueue May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreStateMachine::getQueue May 31 18:38:18 volumio-mackie volumio[1205]: info: CorePlayQueue::getQueue May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 18:38:18 volumio-mackie volumio[1205]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 18:38:18 volumio-mackie volumio[1205]: info: Received Get System Version May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 18:38:18 volumio-mackie volumio[1205]: info: Received Get System Info May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 18:38:18 volumio-mackie volumio[1205]: info: Discovery: Getting this device information May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:18 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:18 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 18:38:19 volumio-mackie volumio[1205]: info: MRS: Removed streaming files May 31 18:38:19 volumio-mackie volumio[1205]: info: MRS: volumioStreaming STOPPED May 31 18:38:19 volumio-mackie volumio[1205]: info: MRS: SNAPSERVER STOPPED May 31 18:38:19 volumio-mackie volumio[1205]: info: MRS: SNAPCLIENT STOPPED May 31 18:38:19 volumio-mackie volumio[1205]: info: Discovery: Disconnected from remote: 192.168.1.123 May 31 18:38:19 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output for this device May 31 18:38:19 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:19 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:19 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:19 volumio-mackie volumio[1205]: info: Discovery: Disconnected from remote: 192.168.1.123 May 31 18:38:19 volumio-mackie volumio[1205]: info: Discovery: Disconnected from remote: 192.168.1.223 May 31 18:38:20 volumio-mackie volumio[1205]: info: MRS: Adding multiroomSync output May 31 18:38:20 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:20 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:20 volumio-mackie volumio[1205]: info: MRS: Adding multiroomSync output May 31 18:38:20 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:20 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:20 volumio-mackie volumio[1205]: info: CoreCommandRouter::Close All Modals sent May 31 18:38:20 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted May 31 18:38:21 volumio-mackie volumio[1205]: info: Asound.conf file unchanged, so no further update is needed May 31 18:38:21 volumio-mackie volumio[1205]: info: Output device has changed, restarting MPD May 31 18:38:21 volumio-mackie volumio[1205]: info: Output device has changed, restarting Shairport Sync May 31 18:38:21 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:21 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:21 volumio-mackie sudo[2730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 18:38:21 volumio-mackie sudo[2730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:21 volumio-mackie sudo[2730]: pam_unix(sudo:session): session closed for user root May 31 18:38:21 volumio-mackie sudo[2734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 18:38:21 volumio-mackie volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 18:38:21 volumio-mackie volumio[1205]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 18:38:21 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:21 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 31 18:38:21 volumio-mackie sudo[2734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:21 volumio-mackie volumio[1205]: ------------------------------------ BT MESSAGE: [FUNC] onStart May 31 18:38:21 volumio-mackie volumio[1205]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service May 31 18:38:21 volumio-mackie volumio[1205]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp May 31 18:38:21 volumio-mackie volumio[1205]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ May 31 18:38:21 volumio-mackie volumio[1205]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. May 31 18:38:21 volumio-mackie systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 18:38:21 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 31 18:38:21 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 31 18:38:21 volumio-mackie volumio[1205]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 18:38:21 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:21 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 31 18:38:21 volumio-mackie volumio[1205]: info: Adding METAVOLUMIO REST API Endpoints May 31 18:38:21 volumio-mackie volumio[1205]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 31 18:38:21 volumio-mackie volumio[1205]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 31 18:38:21 volumio-mackie volumio[1205]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 31 18:38:21 volumio-mackie bluetoothd[830]: Path / reserved for Adv Monitor app :1.47 May 31 18:38:21 volumio-mackie volumio[1205]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 31 18:38:21 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 31 18:38:21 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 31 18:38:21 volumio-mackie volumio[1205]: info: Preparing CD Folders May 31 18:38:21 volumio-mackie volumio[1205]: info: Adding CD REST API Endpoints May 31 18:38:21 volumio-mackie volumio[1205]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 31 18:38:21 volumio-mackie volumio[1205]: info: Starting UDEV Watcher for CD May 31 18:38:21 volumio-mackie volumio[1205]: info: Detecting CD presence with UDEV May 31 18:38:21 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 31 18:38:21 volumio-mackie bluetoothd[830]: Adv Monitor app :1.47 disconnected from D-Bus May 31 18:38:22 volumio-mackie systemd[1]: mpd.service: Deactivated successfully. May 31 18:38:22 volumio-mackie systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 18:38:22 volumio-mackie systemd[1]: mpd.service: Consumed 4.897s CPU time. May 31 18:38:22 volumio-mackie systemd[1]: mpd.socket: Deactivated successfully. May 31 18:38:22 volumio-mackie systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 18:38:22 volumio-mackie systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 18:38:22 volumio-mackie systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 18:38:22 volumio-mackie systemd[1]: Starting mpd.service - Music Player Daemon... May 31 18:38:22 volumio-mackie sudo[2761]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 18:38:22 volumio-mackie sudo[2761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 18:38:22 volumio-mackie sudo[2761]: pam_unix(sudo:session): session closed for user root May 31 18:38:25 volumio-mackie mpd[2766]: 2026-05-31T18:38:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 18:38:25 volumio-mackie systemd[1]: Started mpd.service - Music Player Daemon. May 31 18:38:25 volumio-mackie sudo[2734]: pam_unix(sudo:session): session closed for user root May 31 18:38:27 volumio-mackie volumio[1205]: warn: [cd-plugin] cdspeedctl: device or media not ready May 31 18:38:27 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 31 18:38:27 volumio-mackie volumio[1205]: info: Adding inputs REST Endpoints May 31 18:38:27 volumio-mackie volumio[1205]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 31 18:38:27 volumio-mackie volumio[1205]: info: Scanning Audio Inputs May 31 18:38:27 volumio-mackie volumio[1205]: info: Checking against Known Cards name May 31 18:38:27 volumio-mackie volumio[1205]: info: Adding Server instance for streaming May 31 18:38:27 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 31 18:38:27 volumio-mackie volumio[1205]: error: Hi Res Audio Failed Login: Missing Login Data May 31 18:38:27 volumio-mackie volumio[1205]: info: Adding HIGHRESAUDIO REST API Endpoints May 31 18:38:27 volumio-mackie volumio[1205]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 31 18:38:27 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 31 18:38:27 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 31 18:38:27 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 31 18:38:27 volumio-mackie volumio[1205]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 31 18:38:27 volumio-mackie volumio[1205]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 31 18:38:27 volumio-mackie sudo[2776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:38:27 volumio-mackie volumio[1205]: info: QobuzConnect: Starting Qobuz Connect socket and service May 31 18:38:27 volumio-mackie sudo[2776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:27 volumio-mackie volumio[1205]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 31 18:38:27 volumio-mackie volumio[1205]: info: Adding TIDAL REST API Endpoints May 31 18:38:27 volumio-mackie volumio[1205]: info: Stopping AccessToken refresher cron for QOBUZ May 31 18:38:28 volumio-mackie sudo[2776]: pam_unix(sudo:session): session closed for user root May 31 18:38:28 volumio-mackie sudo[2780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 18:38:28 volumio-mackie sudo[2780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:28 volumio-mackie volumio[1205]: info: AccessToken refresher cron started for QOBUZ May 31 18:38:28 volumio-mackie volumio[1205]: info: Adding QOBUZ REST API Endpoints May 31 18:38:28 volumio-mackie sudo[2780]: pam_unix(sudo:session): session closed for user root May 31 18:38:28 volumio-mackie sudo[2784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 18:38:28 volumio-mackie volumio[1205]: info: Discovery: Remote connection error: 192.168.1.123 May 31 18:38:28 volumio-mackie volumio[1205]: info: Discovery: Remote connection error: 192.168.1.123 May 31 18:38:28 volumio-mackie volumio[1205]: info: Discovery: Remote connection timeout: 192.168.1.123 May 31 18:38:28 volumio-mackie volumio[1205]: info: Discovery: Remote connection timeout: 192.168.1.123 May 31 18:38:28 volumio-mackie volumio[1205]: info: MRS: Getting audio outputs on start May 31 18:38:28 volumio-mackie volumio[1205]: info: MRS: Requesting all other devices output May 31 18:38:28 volumio-mackie sudo[2784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:28 volumio-mackie systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 31 18:38:28 volumio-mackie sudo[2784]: pam_unix(sudo:session): session closed for user root May 31 18:38:29 volumio-mackie volumio[1205]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on May 31 18:38:29 volumio-mackie volumio[1205]: info: MPD Permissions set May 31 18:38:29 volumio-mackie sudo[2788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 31 18:38:29 volumio-mackie sudo[2788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:29 volumio-mackie volumio[1205]: info: Successfully Updated MyVolumio device May 31 18:38:29 volumio-mackie sudo[2791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 31 18:38:29 volumio-mackie sudo[2791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:29 volumio-mackie systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. May 31 18:38:30 volumio-mackie sudo[2788]: pam_unix(sudo:session): session closed for user root May 31 18:38:30 volumio-mackie volumiobt[2796]: INFO [BTSTART] Ensuring Bluetooth directory exists... May 31 18:38:30 volumio-mackie sudo[2797]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth May 31 18:38:30 volumio-mackie sudo[2797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:30 volumio-mackie sudo[2797]: pam_unix(sudo:session): session closed for user root May 31 18:38:30 volumio-mackie systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 31 18:38:30 volumio-mackie systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 31 18:38:30 volumio-mackie systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. May 31 18:38:30 volumio-mackie sudo[2791]: pam_unix(sudo:session): session closed for user root May 31 18:38:30 volumio-mackie sudo[2799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth May 31 18:38:30 volumio-mackie sudo[2799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:30 volumio-mackie sudo[2799]: pam_unix(sudo:session): session closed for user root May 31 18:38:30 volumio-mackie volumiobt[2805]: INFO [BTSTART] Powering on Bluetooth if needed... May 31 18:38:30 volumio-mackie volumio[1205]: info: Successfully Updated MyVolumio device May 31 18:38:30 volumio-mackie autossh[2801]: port set to 0, monitoring disabled May 31 18:38:30 volumio-mackie autossh[2801]: starting ssh (count 1) May 31 18:38:30 volumio-mackie autossh[2801]: ssh child pid is 2808 May 31 18:38:30 volumio-mackie bluetoothd[830]: Path / reserved for Adv Monitor app :1.48 May 31 18:38:30 volumio-mackie bluetoothd[830]: Adv Monitor app :1.48 disconnected from D-Bus May 31 18:38:30 volumio-mackie volumiobt[2809]: INFO [BTSTART] Making Bluetooth discoverable and pairable... May 31 18:38:30 volumio-mackie volumiobt[2810]: [198B blob data] May 31 18:38:30 volumio-mackie volumiobt[2810]: [162B blob data] May 31 18:38:30 volumio-mackie volumiobt[2810]: [162B blob data] May 31 18:38:30 volumio-mackie volumiobt[2810]: [162B blob data] May 31 18:38:30 volumio-mackie volumiobt[2810]: [118B blob data] May 31 18:38:30 volumio-mackie volumiobt[2810]: [7F-B9-B3-98-CC-07]> discoverable on May 31 18:38:30 volumio-mackie volumiobt[2810]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended May 31 18:38:30 volumio-mackie volumiobt[2810]: [7F-B9-B3-98-CC-07]> pairable on May 31 18:38:30 volumio-mackie volumiobt[2810]: [136B blob data] May 31 18:38:30 volumio-mackie bluetoothd[830]: Path / reserved for Adv Monitor app :1.49 May 31 18:38:30 volumio-mackie bluetoothd[830]: Adv Monitor app :1.49 disconnected from D-Bus May 31 18:38:30 volumio-mackie volumiobt[2810]: [7F-B9-B3-98-CC-07]> May 31 18:38:30 volumio-mackie volumiobt[2813]: INFO [BTSTART] Registering Bluetooth agent... May 31 18:38:30 volumio-mackie volumiobt[2814]: [NEW] Media /org/bluez/hci0 May 31 18:38:30 volumio-mackie volumiobt[2814]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb May 31 18:38:30 volumio-mackie volumiobt[2814]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb May 31 18:38:30 volumio-mackie volumiobt[2814]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb May 31 18:38:30 volumio-mackie bluetoothd[830]: Adv Monitor app :1.50 disconnected from D-Bus May 31 18:38:30 volumio-mackie volumiobt[2815]: No agent is registered May 31 18:38:30 volumio-mackie volumiobt[2815]: [NEW] Media /org/bluez/hci0 May 31 18:38:30 volumio-mackie volumiobt[2815]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb May 31 18:38:30 volumio-mackie volumiobt[2815]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb May 31 18:38:30 volumio-mackie volumiobt[2815]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb May 31 18:38:30 volumio-mackie volumio[1205]: info: MRS: Pushing audio output on request May 31 18:38:30 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:30 volumio-mackie bluetoothd[830]: Adv Monitor app :1.51 disconnected from D-Bus May 31 18:38:30 volumio-mackie volumiobt[2816]: INFO [BTSTART] Agent registered successfully. May 31 18:38:30 volumio-mackie volumiobt[2817]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... May 31 18:38:30 volumio-mackie volumio[1205]: info: Discovery: Connected to remote: 192.168.1.223 May 31 18:38:30 volumio-mackie volumiossh-tunnel[2808]: Warning: Permanently added '[us3.myvolumio.org]:2222' (RSA) to the list of known hosts. May 31 18:38:31 volumio-mackie volumiobt[2818]: 2026-05-31 18:38:31 a2dp-agent [INFO] Connecting to system D-Bus May 31 18:38:31 volumio-mackie volumiobt[2818]: 2026-05-31 18:38:31 a2dp-agent [INFO] Connected to system D-Bus May 31 18:38:31 volumio-mackie volumiobt[2818]: 2026-05-31 18:38:31 bluezutils [INFO] Found adapter at: /org/bluez/hci0 May 31 18:38:31 volumio-mackie volumiobt[2818]: 2026-05-31 18:38:31 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 May 31 18:38:31 volumio-mackie volumiobt[2818]: 2026-05-31 18:38:31 a2dp-agent [INFO] Set DiscoverableTimeout to infinite May 31 18:38:31 volumio-mackie volumiobt[2818]: 2026-05-31 18:38:31 a2dp-agent [INFO] Enabled Discoverable mode May 31 18:38:31 volumio-mackie volumiobt[2818]: 2026-05-31 18:38:31 a2dp-agent [INFO] Agent registered at /local/a2dpagent May 31 18:38:31 volumio-mackie volumiobt[2818]: 2026-05-31 18:38:31 a2dp-agent [INFO] Agent set as default May 31 18:38:31 volumio-mackie volumiobt[2818]: 2026-05-31 18:38:31 a2dp-agent [INFO] A2DP agent running, waiting for connections... May 31 18:38:31 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:32 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:32 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:32 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:32 volumio-mackie volumio[1205]: info: Remote SSH Started May 31 18:38:34 volumio-mackie volumio[1205]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully May 31 18:38:34 volumio-mackie volumio[1205]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:34 volumio-mackie volumio[1205]: error: updateQueue error: null May 31 18:38:35 volumio-mackie volumio[1205]: info: Executing endpoint qc_getconfig May 31 18:38:35 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.080 [2786.2786] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 31 18:38:36 volumio-mackie volumio[1205]: info: MRS: Pushing audio output on request May 31 18:38:36 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.222 [2786.2786] INFO VolumeManager: [0x1859f48]: Setting new playback volume: 75 May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.222 [2786.2786] INFO VolumeManager: [0x1859f48]: Setting new mute state: 0 May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.222 [2786.2786] INFO AudioStreamManager: [0x1859ca0]: Setting new audio download buffer size: 1048576 May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.222 [2786.2786] INFO QobuzConnect: [0x185a810]: Client initialized! May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.222 [2786.2786] INFO SampleApp: Starting Avahi advertising, name: Volumio-Mackie, service name: _qobuz-connect._tcp May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.250 [2786.2786] INFO LocalConfigManager: [0x18599c8]: Starting Local Configuration server May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.250 [2786.2786] INFO SampleApp: Starting Local configuration server May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.251 [2786.2786] INFO SampleApp: Connected to UNIX socket client 0x1844818 May 31 18:38:36 volumio-mackie qobuz-connect[2786]: 20260531 18:38:36.251 [2786.2786] INFO SampleApp: Playback volume changed: 75 May 31 18:38:36 volumio-mackie volumio[1205]: info: Discovery: Connected to remote: 192.168.1.123 May 31 18:38:36 volumio-mackie volumio[1205]: info: TidalConnect service stoped! May 31 18:38:37 volumio-mackie volumio[1205]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 31 18:38:37 volumio-mackie volumio[1205]: info: QobuzConnect: QOBUZ Connect daemon connected May 31 18:38:37 volumio-mackie volumio[1205]: info: MRS: Found cast device: Google-TV-Streamer-dfbd262c88a10e0f0d2ec40f7d1acc60 May 31 18:38:37 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:37 volumio-mackie volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 31 18:38:37 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:37 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:37 volumio-mackie volumio[1205]: info: Starting Shairport Sync May 31 18:38:37 volumio-mackie volumio[1205]: info: MRS: Found cast device: Google-Nest-Hub-Max-19ae5207978da9f39c2784b0ef1eb436 May 31 18:38:37 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:38 volumio-mackie sudo[2863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 18:38:38 volumio-mackie sudo[2863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:38 volumio-mackie systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 18:38:38 volumio-mackie systemd[1]: shairport-sync.service: Deactivated successfully. May 31 18:38:38 volumio-mackie systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:38:38 volumio-mackie systemd[1]: shairport-sync.service: Consumed 2.096s CPU time. May 31 18:38:38 volumio-mackie systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:38:38 volumio-mackie sudo[2863]: pam_unix(sudo:session): session closed for user root May 31 18:38:38 volumio-mackie volumio[1205]: info: Preparing to generate the ALSA configuration file May 31 18:38:39 volumio-mackie volumio[1205]: info: MRS: Found cast device: Google-Home-Mini-12fccda9d75762d5377a8b019fcc3921 May 31 18:38:39 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:39 volumio-mackie volumio[1205]: info: Shairport-Sync Started May 31 18:38:39 volumio-mackie volumio[1205]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 18:38:39 volumio-mackie volumio[1205]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 18:38:39 volumio-mackie volumio[1205]: info: MRS: Found cast device: Chromecast-2295b8547b8d4cf2bed97454a9de36c9 May 31 18:38:39 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:40 volumio-mackie volumio[1205]: info: MRS: Found cast device: Chromecast-e7780fc6f4d6febcc345bd327fcdb784 May 31 18:38:40 volumio-mackie volumio[1205]: info: Adding audio output: May 31 18:38:40 volumio-mackie volumio[1205]: info: Asound.conf file unchanged, so no further update is needed May 31 18:38:40 volumio-mackie volumio[1205]: info: Output device has changed, restarting MPD May 31 18:38:40 volumio-mackie volumio[1205]: info: Output device has changed, restarting Shairport Sync May 31 18:38:40 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:40 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:40 volumio-mackie sudo[2882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 18:38:40 volumio-mackie sudo[2882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:40 volumio-mackie sudo[2884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 18:38:40 volumio-mackie sudo[2884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:40 volumio-mackie sudo[2884]: pam_unix(sudo:session): session closed for user root May 31 18:38:40 volumio-mackie volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 18:38:40 volumio-mackie volumio[1205]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 18:38:40 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:40 volumio-mackie sudo[2889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 18:38:40 volumio-mackie sudo[2889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:40 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:38:40 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:40 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:40 volumio-mackie systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 31 18:38:40 volumio-mackie sudo[2882]: pam_unix(sudo:session): session closed for user root May 31 18:38:40 volumio-mackie systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 18:38:40 volumio-mackie volumio[1205]: info: Preparing to generate the ALSA configuration file May 31 18:38:40 volumio-mackie systemd[1]: mpd.service: Deactivated successfully. May 31 18:38:40 volumio-mackie systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 18:38:40 volumio-mackie systemd[1]: mpd.service: Consumed 2.963s CPU time. May 31 18:38:40 volumio-mackie systemd[1]: mpd.socket: Deactivated successfully. May 31 18:38:40 volumio-mackie systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 18:38:40 volumio-mackie systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 18:38:40 volumio-mackie sudo[2903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:38:40 volumio-mackie sudo[2903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:40 volumio-mackie systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 18:38:40 volumio-mackie systemd[1]: Starting mpd.service - Music Player Daemon... May 31 18:38:41 volumio-mackie systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 31 18:38:41 volumio-mackie systemd[1]: vtcs.service: Deactivated successfully. May 31 18:38:41 volumio-mackie systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 31 18:38:41 volumio-mackie volumio[1205]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 18:38:41 volumio-mackie volumio[1205]: info: Reading ALSA contributions from plugins. May 31 18:38:41 volumio-mackie sudo[2900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:38:41 volumio-mackie sudo[2900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:41 volumio-mackie sudo[2903]: pam_unix(sudo:session): session closed for user root May 31 18:38:41 volumio-mackie sudo[2907]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 18:38:41 volumio-mackie sudo[2907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 18:38:41 volumio-mackie sudo[2907]: pam_unix(sudo:session): session closed for user root May 31 18:38:41 volumio-mackie sudo[2911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 18:38:41 volumio-mackie volumio[1205]: info: MPD Permissions set May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:41 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:41 volumio-mackie sudo[2911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:41 volumio-mackie sudo[2911]: pam_unix(sudo:session): session closed for user root May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:41 volumio-mackie sudo[2900]: pam_unix(sudo:session): session closed for user root May 31 18:38:41 volumio-mackie sudo[2922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 18:38:41 volumio-mackie sudo[2922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:41 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:41 volumio-mackie systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 31 18:38:41 volumio-mackie qobuz-connect[2786]: 20260531 18:38:41.714 [2786.2786] INFO SampleApp: Stopping Local configuration server May 31 18:38:42 volumio-mackie volumio[1205]: info: Starting Shairport Sync May 31 18:38:42 volumio-mackie qobuz-connect[2786]: 20260531 18:38:42.259 [2786.2786] INFO SampleApp: shat down connection on UNIX socket May 31 18:38:42 volumio-mackie systemd[1]: qobuz-connect.service: Deactivated successfully. May 31 18:38:42 volumio-mackie systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 31 18:38:42 volumio-mackie volumio[1205]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 31 18:38:42 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:38:42 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:42 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:42 volumio-mackie sudo[2926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 18:38:42 volumio-mackie sudo[2926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:42 volumio-mackie systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 31 18:38:42 volumio-mackie sudo[2922]: pam_unix(sudo:session): session closed for user root May 31 18:38:42 volumio-mackie volumio[1205]: info: Executing endpoint qc_getconfig May 31 18:38:42 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 31 18:38:42 volumio-mackie systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 18:38:42 volumio-mackie systemd[1]: shairport-sync.service: Deactivated successfully. May 31 18:38:42 volumio-mackie systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:38:42 volumio-mackie systemd[1]: shairport-sync.service: Consumed 1.547s CPU time. May 31 18:38:42 volumio-mackie systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:38:42 volumio-mackie sudo[2926]: pam_unix(sudo:session): session closed for user root May 31 18:38:42 volumio-mackie qobuz-connect[2927]: 20260531 18:38:42.965 [2927.2927] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 31 18:38:42 volumio-mackie qobuz-connect[2927]: 20260531 18:38:42.972 [2927.2927] INFO VolumeManager: [0x17e1f48]: Setting new playback volume: 75 May 31 18:38:42 volumio-mackie qobuz-connect[2927]: 20260531 18:38:42.972 [2927.2927] INFO VolumeManager: [0x17e1f48]: Setting new mute state: 0 May 31 18:38:42 volumio-mackie qobuz-connect[2927]: 20260531 18:38:42.972 [2927.2927] INFO AudioStreamManager: [0x17e1ca0]: Setting new audio download buffer size: 1048576 May 31 18:38:42 volumio-mackie qobuz-connect[2927]: 20260531 18:38:42.972 [2927.2927] INFO QobuzConnect: [0x17e2810]: Client initialized! May 31 18:38:42 volumio-mackie qobuz-connect[2927]: 20260531 18:38:42.972 [2927.2927] INFO SampleApp: Starting Avahi advertising, name: Volumio-Mackie, service name: _qobuz-connect._tcp May 31 18:38:43 volumio-mackie qobuz-connect[2927]: 20260531 18:38:43.013 [2927.2927] INFO LocalConfigManager: [0x17e19c8]: Starting Local Configuration server May 31 18:38:43 volumio-mackie qobuz-connect[2927]: 20260531 18:38:43.013 [2927.2927] INFO SampleApp: Starting Local configuration server May 31 18:38:43 volumio-mackie qobuz-connect[2927]: 20260531 18:38:43.014 [2927.2927] INFO SampleApp: Connected to UNIX socket client 0x17cc818 May 31 18:38:43 volumio-mackie qobuz-connect[2927]: 20260531 18:38:43.115 [2927.2927] INFO SampleApp: Playback volume changed: 75 May 31 18:38:43 volumio-mackie volumio[1205]: info: Asound.conf file written May 31 18:38:43 volumio-mackie sudo[2965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 18:38:43 volumio-mackie sudo[2965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:43 volumio-mackie sudo[2965]: pam_unix(sudo:session): session closed for user root May 31 18:38:44 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 31 18:38:44 volumio-mackie volumio[1205]: No state is present for card b1 May 31 18:38:44 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 31 18:38:44 volumio-mackie volumio[1205]: /usr/share/alsa/init/bcm2835:14: control element not found May 31 18:38:44 volumio-mackie volumio[1205]: No state is present for card b1 May 31 18:38:44 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 31 18:38:44 volumio-mackie volumio[1205]: No state is present for card Headphones May 31 18:38:44 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 31 18:38:44 volumio-mackie volumio[1205]: /usr/share/alsa/init/bcm2835:20: control element not found May 31 18:38:44 volumio-mackie volumio[1205]: No state is present for card Headphones May 31 18:38:44 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 31 18:38:44 volumio-mackie volumio[1205]: No state is present for card vc4hdmi May 31 18:38:44 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 31 18:38:44 volumio-mackie volumio[1205]: Found hardware: "vc4-hdmi" "" "" "" "" May 31 18:38:44 volumio-mackie volumio[1205]: Hardware is initialized using a generic method May 31 18:38:44 volumio-mackie volumio[1205]: No state is present for card vc4hdmi May 31 18:38:44 volumio-mackie volumio[1205]: info: Output device has changed, restarting MPD May 31 18:38:44 volumio-mackie volumio[1205]: info: Output device has changed, restarting Shairport Sync May 31 18:38:44 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:44 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:44 volumio-mackie sudo[2973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 18:38:44 volumio-mackie sudo[2973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:44 volumio-mackie systemd[1]: mpd.service: Deactivated successfully. May 31 18:38:44 volumio-mackie systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 18:38:44 volumio-mackie systemd[1]: mpd.service: Consumed 2.870s CPU time. May 31 18:38:44 volumio-mackie sudo[2971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 18:38:44 volumio-mackie systemd[1]: mpd.socket: Deactivated successfully. May 31 18:38:44 volumio-mackie systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 18:38:44 volumio-mackie systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 18:38:44 volumio-mackie sudo[2971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:44 volumio-mackie systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 18:38:44 volumio-mackie volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 18:38:44 volumio-mackie volumio[1205]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 18:38:44 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:44 volumio-mackie systemd[1]: Starting mpd.service - Music Player Daemon... May 31 18:38:44 volumio-mackie sudo[2971]: pam_unix(sudo:session): session closed for user root May 31 18:38:44 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:38:44 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:44 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:44 volumio-mackie sudo[2985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:38:44 volumio-mackie sudo[2985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:44 volumio-mackie sudo[2981]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 18:38:44 volumio-mackie sudo[2981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 18:38:44 volumio-mackie sudo[2981]: pam_unix(sudo:session): session closed for user root May 31 18:38:44 volumio-mackie sudo[2987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:38:44 volumio-mackie sudo[2987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:44 volumio-mackie volumio[1205]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 18:38:44 volumio-mackie volumio[1205]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 31 18:38:44 volumio-mackie volumio[1205]: info: QobuzConnect: QOBUZ Connect daemon connected May 31 18:38:44 volumio-mackie volumio[1205]: info: Shairport-Sync Started May 31 18:38:45 volumio-mackie sudo[2985]: pam_unix(sudo:session): session closed for user root May 31 18:38:45 volumio-mackie sudo[2997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 18:38:45 volumio-mackie sudo[2997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:45 volumio-mackie sudo[2987]: pam_unix(sudo:session): session closed for user root May 31 18:38:45 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:45 volumio-mackie volumio[1205]: info: MPD Permissions set May 31 18:38:45 volumio-mackie volumio[1205]: info: TidalConnect service stoped! May 31 18:38:45 volumio-mackie volumio[1205]: info: TidalConnect service stoped! May 31 18:38:45 volumio-mackie sudo[2997]: pam_unix(sudo:session): session closed for user root May 31 18:38:45 volumio-mackie volumio[1205]: info: TidalConnect service started! May 31 18:38:45 volumio-mackie sudo[3006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 18:38:45 volumio-mackie sudo[3006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:45 volumio-mackie systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 31 18:38:45 volumio-mackie qobuz-connect[2927]: 20260531 18:38:45.580 [2927.2927] INFO SampleApp: Stopping Local configuration server May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:38:45 volumio-mackie volumio[1205]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 18:38:45 volumio-mackie volumio[1205]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 31 18:38:46 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 18:38:46 volumio-mackie volumio[1205]: info: Starting Shairport Sync May 31 18:38:47 volumio-mackie qobuz-connect[2927]: 20260531 18:38:47.032 [2927.2927] INFO SampleApp: shat down connection on UNIX socket May 31 18:38:47 volumio-mackie systemd[1]: qobuz-connect.service: Deactivated successfully. May 31 18:38:47 volumio-mackie systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 31 18:38:47 volumio-mackie volumio[1205]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 31 18:38:47 volumio-mackie systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 31 18:38:47 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:38:47 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:47 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:47 volumio-mackie sudo[3006]: pam_unix(sudo:session): session closed for user root May 31 18:38:47 volumio-mackie volumio[1205]: info: Executing endpoint qc_getconfig May 31 18:38:47 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 31 18:38:47 volumio-mackie sudo[3029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 18:38:47 volumio-mackie sudo[3029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:47 volumio-mackie sudo[3031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 18:38:47 volumio-mackie sudo[3031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.480 [3028.3028] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.486 [3028.3028] INFO VolumeManager: [0x9d5f48]: Setting new playback volume: 75 May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.486 [3028.3028] INFO VolumeManager: [0x9d5f48]: Setting new mute state: 0 May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.486 [3028.3028] INFO AudioStreamManager: [0x9d5ca0]: Setting new audio download buffer size: 1048576 May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.486 [3028.3028] INFO QobuzConnect: [0x9d6810]: Client initialized! May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.486 [3028.3028] INFO SampleApp: Starting Avahi advertising, name: Volumio-Mackie, service name: _qobuz-connect._tcp May 31 18:38:47 volumio-mackie volumio[1205]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 31 18:38:47 volumio-mackie volumio[1205]: info: QobuzConnect: QOBUZ Connect daemon connected May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.528 [3028.3028] INFO LocalConfigManager: [0x9d59c8]: Starting Local Configuration server May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.528 [3028.3028] INFO SampleApp: Starting Local configuration server May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.528 [3028.3028] INFO SampleApp: Connected to UNIX socket client 0x9c0818 May 31 18:38:47 volumio-mackie systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 18:38:47 volumio-mackie systemd[1]: shairport-sync.service: Deactivated successfully. May 31 18:38:47 volumio-mackie systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:38:47 volumio-mackie systemd[1]: shairport-sync.service: Consumed 1.343s CPU time. May 31 18:38:47 volumio-mackie qobuz-connect[3028]: 20260531 18:38:47.630 [3028.3028] INFO SampleApp: Playback volume changed: 75 May 31 18:38:47 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:47 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:47 volumio-mackie systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:38:47 volumio-mackie sudo[3029]: pam_unix(sudo:session): session closed for user root May 31 18:38:47 volumio-mackie volumio[1205]: info: Shairport-Sync Started May 31 18:38:47 volumio-mackie mpd[2992]: 2026-05-31T18:38:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 18:38:47 volumio-mackie systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 31 18:38:47 volumio-mackie systemd[1]: Started mpd.service - Music Player Daemon. May 31 18:38:47 volumio-mackie sudo[2889]: pam_unix(sudo:session): session closed for user root May 31 18:38:47 volumio-mackie sudo[2973]: pam_unix(sudo:session): session closed for user root May 31 18:38:48 volumio-mackie sudo[3031]: pam_unix(sudo:session): session closed for user root May 31 18:38:48 volumio-mackie volumio[1205]: error: MPD error: The expression evaluated to a falsy value: May 31 18:38:48 volumio-mackie volumio[1205]: assert.ok(self.idling) May 31 18:38:48 volumio-mackie volumio[1205]: error: The expression evaluated to a falsy value: May 31 18:38:48 volumio-mackie volumio[1205]: assert.ok(self.idling) May 31 18:38:48 volumio-mackie volumio[1205]: info: TidalConnect service stoped! May 31 18:38:48 volumio-mackie volumio[1205]: info: TidalConnect service stoped! May 31 18:38:48 volumio-mackie volumio[1205]: info: Executing endpoint tc_getconfig May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 18:38:48 volumio-mackie vtcs[3056]: STARTING TidalConnect services, version: 1.6.1 May 31 18:38:48 volumio-mackie vtcs[3056]: STARTED TidalConnect services. May 31 18:38:48 volumio-mackie volumio[1205]: error: updateQueue error: null May 31 18:38:48 volumio-mackie qobuz-connect[3028]: 20260531 18:38:48.605 [3028.3037] INFO SampleApp: API endpoint invoked: get-display-info May 31 18:38:48 volumio-mackie volumio[1205]: info: Executing endpoint tc_connect May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 31 18:38:48 volumio-mackie volumio[1205]: info: Connecting to TidalConnect May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::servicePushState May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreStateMachine::pushState May 31 18:38:48 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioPushState May 31 18:38:48 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output update for this device May 31 18:38:48 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:48 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:48 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:48 volumio-mackie volumio[1205]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 31 18:38:48 volumio-mackie volumio[1205]: verbose: CURRENT POSITION 0 May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreStateMachine::syncState stateService stop May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreStateMachine::syncState currentStatus stop May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreStateMachine::pushState May 31 18:38:48 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioPushState May 31 18:38:48 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output update for this device May 31 18:38:48 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:48 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:48 volumio-mackie volumio[1205]: info: No code May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreStateMachine::pushState May 31 18:38:48 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioPushState May 31 18:38:48 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output update for this device May 31 18:38:48 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:48 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:48 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreCommandRouter::servicePushState May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreStateMachine::pushState May 31 18:38:49 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioPushState May 31 18:38:49 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output update for this device May 31 18:38:49 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:49 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:49 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:49 volumio-mackie volumio[1205]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 31 18:38:49 volumio-mackie volumio[1205]: verbose: CURRENT POSITION 0 May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreStateMachine::syncState stateService stop May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreStateMachine::syncState currentStatus stop May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreStateMachine::pushState May 31 18:38:49 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioPushState May 31 18:38:49 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output update for this device May 31 18:38:49 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:49 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:49 volumio-mackie volumio[1205]: info: No code May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreStateMachine::pushState May 31 18:38:49 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioPushState May 31 18:38:49 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output update for this device May 31 18:38:49 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:38:49 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:38:49 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.451Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" state=STATUS_STOPPED positionMs=0 volume=86 May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.451Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" id= title= May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.465Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" state=STATUS_STOPPED positionMs=0 volume=86 May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.465Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" id= title= May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.512Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" state=STATUS_STOPPED positionMs=0 volume=86 May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.512Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" id= title= May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.532Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" state=STATUS_STOPPED positionMs=0 volume=86 May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.532Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" id= title= May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.535Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" state=STATUS_STOPPED positionMs=0 volume=86 May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.536Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" id= title= May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.573Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" state=STATUS_STOPPED positionMs=0 volume=86 May 31 18:38:49 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:38:49.573Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" id= title= May 31 18:38:49 volumio-mackie qobuz-connect[3028]: 20260531 18:38:49.930 [3028.3037] INFO SampleApp: API endpoint invoked: get-display-info May 31 18:38:50 volumio-mackie volumio[1205]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 18:38:50 volumio-mackie volumio[1205]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 18:38:51 volumio-mackie volumio[1205]: info: TidalConnect service started! May 31 18:38:51 volumio-mackie sudo[3086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 18:38:51 volumio-mackie sudo[3086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:38:51 volumio-mackie sudo[3086]: pam_unix(sudo:session): session closed for user root May 31 18:38:54 volumio-mackie volumio[1205]: info: TidalConnect service started! May 31 18:39:06 volumio-mackie volumio[1205]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 31 18:39:06 volumio-mackie volumio[1205]: info: Preparing to save Alsa Options, stopping services first May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:39:06 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioPause May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreStateMachine::pause May 31 18:39:06 volumio-mackie volumio[1205]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"}} May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:06 volumio-mackie volumio[1205]: info: Enabling I2S DAC: HiFiBerry DAC May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:06 volumio-mackie sudo[3126]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 18:39:06 volumio-mackie sudo[3126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:06 volumio-mackie sudo[3126]: pam_unix(sudo:session): session closed for user root May 31 18:39:06 volumio-mackie volumio[1205]: info: No Overlays Loaded May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:06 volumio-mackie sudo[3129]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac May 31 18:39:06 volumio-mackie sudo[3129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:06 volumio-mackie volumio[1205]: warn: Unable to locate the device HDMI Out a reboot may be required. May 31 18:39:06 volumio-mackie sudo[3129]: pam_unix(sudo:session): session closed for user root May 31 18:39:06 volumio-mackie kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status May 31 18:39:06 volumio-mackie kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible May 31 18:39:06 volumio-mackie kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller May 31 18:39:06 volumio-mackie kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:06 volumio-mackie volumio[1205]: info: Device 2 does not have any Mixer Control Available May 31 18:39:06 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:39:06 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:06 volumio-mackie vtcs[3056]: [2026-05-31 18:39:06.687] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 31 18:39:06 volumio-mackie vtcs[3056]: [2026-05-31 18:39:06.688] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer May 31 18:39:06 volumio-mackie vtcs[3056]: [2026-05-31 18:39:06.688] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected May 31 18:39:06 volumio-mackie (udev-worker)[3140]: controlC3: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 3' failed with exit code 99. May 31 18:39:06 volumio-mackie sudo[3150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:06 volumio-mackie sudo[3150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 31 18:39:06 volumio-mackie volumio[1205]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 31 18:39:06 volumio-mackie volumio[1205]: info: Disabling external Volume Control May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 18:39:06 volumio-mackie systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 31 18:39:06 volumio-mackie systemd[1]: vtcs.service: Deactivated successfully. May 31 18:39:06 volumio-mackie systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreStateMachine::pushState May 31 18:39:06 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioPushState May 31 18:39:06 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output update for this device May 31 18:39:06 volumio-mackie sudo[3150]: pam_unix(sudo:session): session closed for user root May 31 18:39:06 volumio-mackie volumio[1205]: info: MRS: Pushing multiroomSync output May 31 18:39:06 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:39:06 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:06 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:39:06.859Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" state=STATUS_STOPPED positionMs=0 volume=100 May 31 18:39:06 volumio-mackie volumio5-onboarding[1812]: time=2026-05-31T22:39:06.861Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.18:59980 @ 0x30bc060" id= title= May 31 18:39:06 volumio-mackie volumio[1205]: info: Preparing to generate the ALSA configuration file May 31 18:39:06 volumio-mackie sudo[3158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:06 volumio-mackie sudo[3158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:06 volumio-mackie volumio[1205]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 18:39:06 volumio-mackie volumio[1205]: info: Reading ALSA contributions from plugins. May 31 18:39:06 volumio-mackie sudo[3165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 18:39:06 volumio-mackie sudo[3165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie sudo[3158]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie volumio[1205]: info: I2S Param [object Object] successfully enabled May 31 18:39:07 volumio-mackie sudo[3165]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie sudo[3168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 18:39:07 volumio-mackie volumio[1205]: info: Getting Alsa Cards List without I2S DAC May 31 18:39:07 volumio-mackie sudo[3168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:07 volumio-mackie systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 18:39:07 volumio-mackie volumio[1205]: info: Asound.conf file written May 31 18:39:07 volumio-mackie sudo[3184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 18:39:07 volumio-mackie sudo[3184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie sudo[3184]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 31 18:39:07 volumio-mackie volumio[1205]: No state is present for card b1 May 31 18:39:07 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 31 18:39:07 volumio-mackie volumio[1205]: /usr/share/alsa/init/bcm2835:14: control element not found May 31 18:39:07 volumio-mackie volumio[1205]: No state is present for card b1 May 31 18:39:07 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 31 18:39:07 volumio-mackie volumio[1205]: No state is present for card Headphones May 31 18:39:07 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 31 18:39:07 volumio-mackie volumio[1205]: /usr/share/alsa/init/bcm2835:20: control element not found May 31 18:39:07 volumio-mackie volumio[1205]: No state is present for card Headphones May 31 18:39:07 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 31 18:39:07 volumio-mackie volumio[1205]: No state is present for card vc4hdmi May 31 18:39:07 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 31 18:39:07 volumio-mackie volumio[1205]: Found hardware: "vc4-hdmi" "" "" "" "" May 31 18:39:07 volumio-mackie volumio[1205]: Hardware is initialized using a generic method May 31 18:39:07 volumio-mackie volumio[1205]: No state is present for card vc4hdmi May 31 18:39:07 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 31 18:39:07 volumio-mackie volumio[1205]: No state is present for card sndrpihifiberry May 31 18:39:07 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 31 18:39:07 volumio-mackie volumio[1205]: Found hardware: "RPi-simple" "" "" "" "" May 31 18:39:07 volumio-mackie volumio[1205]: Hardware is initialized using a generic method May 31 18:39:07 volumio-mackie volumio[1205]: No state is present for card sndrpihifiberry May 31 18:39:07 volumio-mackie volumio[1205]: info: Output device has changed, restarting MPD May 31 18:39:07 volumio-mackie volumio[1205]: info: Output device has changed, restarting Shairport Sync May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:07 volumio-mackie sudo[3190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 18:39:07 volumio-mackie sudo[3190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie sudo[3190]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 18:39:07 volumio-mackie volumio[1205]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie sudo[3194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 18:39:07 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:39:07 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:07 volumio-mackie sudo[3194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie volumio[1205]: info: Output device has changed, restarting MPD May 31 18:39:07 volumio-mackie qobuz-connect[3028]: 20260531 18:39:07.412 [3028.3028] INFO SampleApp: Stopping Local configuration server May 31 18:39:07 volumio-mackie sudo[3204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 18:39:07 volumio-mackie sudo[3204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie volumio[1205]: info: Output device has changed, restarting Shairport Sync May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:07 volumio-mackie systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 18:39:07 volumio-mackie sudo[3204]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie sudo[3202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:07 volumio-mackie sudo[3202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie sudo[3209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 18:39:07 volumio-mackie sudo[3209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 18:39:07 volumio-mackie volumio[1205]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie systemd[1]: mpd.service: Deactivated successfully. May 31 18:39:07 volumio-mackie systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 18:39:07 volumio-mackie systemd[1]: mpd.service: Consumed 3.110s CPU time. May 31 18:39:07 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:39:07 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:07 volumio-mackie sudo[3217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:07 volumio-mackie sudo[3217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie sudo[3202]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie sudo[3219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:07 volumio-mackie sudo[3219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie sudo[3222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:07 volumio-mackie sudo[3222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie systemd[1]: mpd.socket: Deactivated successfully. May 31 18:39:07 volumio-mackie systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 18:39:07 volumio-mackie systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 18:39:07 volumio-mackie sudo[3232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 18:39:07 volumio-mackie sudo[3232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 18:39:07 volumio-mackie systemd[1]: Starting mpd.service - Music Player Daemon... May 31 18:39:07 volumio-mackie volumio[1205]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 31 18:39:07 volumio-mackie volumio[1205]: info: MPD Permissions set May 31 18:39:07 volumio-mackie volumio[1205]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 31 18:39:07 volumio-mackie volumio[1205]: info: MPD Permissions set May 31 18:39:07 volumio-mackie sudo[3239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 18:39:07 volumio-mackie sudo[3239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie sudo[3232]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie sudo[3239]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:07 volumio-mackie volumio[1205]: info: Starting Shairport Sync May 31 18:39:07 volumio-mackie sudo[3250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 18:39:07 volumio-mackie sudo[3250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie sudo[3249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 18:39:07 volumio-mackie sudo[3219]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie sudo[3249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie volumio[1205]: info: Starting Shairport Sync May 31 18:39:07 volumio-mackie sudo[3217]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie sudo[3222]: pam_unix(sudo:session): session closed for user root May 31 18:39:07 volumio-mackie sudo[3257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 18:39:07 volumio-mackie sudo[3257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:07 volumio-mackie qobuz-connect[3028]: 20260531 18:39:07.949 [3028.3028] INFO SampleApp: shat down connection on UNIX socket May 31 18:39:07 volumio-mackie volumio[1205]: info: Preparing to generate the ALSA configuration file May 31 18:39:07 volumio-mackie sudo[3240]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 18:39:07 volumio-mackie sudo[3240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 18:39:07 volumio-mackie systemd[1]: qobuz-connect.service: Deactivated successfully. May 31 18:39:07 volumio-mackie systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 31 18:39:07 volumio-mackie sudo[3240]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie sudo[3261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 18:39:08 volumio-mackie sudo[3261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie volumio[1205]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 31 18:39:08 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:39:08 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:08 volumio-mackie volumio[1205]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 18:39:08 volumio-mackie volumio[1205]: info: Reading ALSA contributions from plugins. May 31 18:39:08 volumio-mackie volumio[1205]: info: Asound.conf file written May 31 18:39:08 volumio-mackie sudo[3269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 18:39:08 volumio-mackie sudo[3269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie sudo[3269]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 31 18:39:08 volumio-mackie systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 18:39:08 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 31 18:39:08 volumio-mackie volumio[1205]: No state is present for card b1 May 31 18:39:08 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 31 18:39:08 volumio-mackie volumio[1205]: /usr/share/alsa/init/bcm2835:14: control element not found May 31 18:39:08 volumio-mackie volumio[1205]: No state is present for card b1 May 31 18:39:08 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 31 18:39:08 volumio-mackie volumio[1205]: No state is present for card Headphones May 31 18:39:08 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 31 18:39:08 volumio-mackie volumio[1205]: /usr/share/alsa/init/bcm2835:20: control element not found May 31 18:39:08 volumio-mackie volumio[1205]: No state is present for card Headphones May 31 18:39:08 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 31 18:39:08 volumio-mackie volumio[1205]: No state is present for card vc4hdmi May 31 18:39:08 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 31 18:39:08 volumio-mackie volumio[1205]: Found hardware: "vc4-hdmi" "" "" "" "" May 31 18:39:08 volumio-mackie volumio[1205]: Hardware is initialized using a generic method May 31 18:39:08 volumio-mackie volumio[1205]: No state is present for card vc4hdmi May 31 18:39:08 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 31 18:39:08 volumio-mackie volumio[1205]: No state is present for card sndrpihifiberry May 31 18:39:08 volumio-mackie volumio[1205]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 31 18:39:08 volumio-mackie volumio[1205]: Found hardware: "RPi-simple" "" "" "" "" May 31 18:39:08 volumio-mackie volumio[1205]: Hardware is initialized using a generic method May 31 18:39:08 volumio-mackie volumio[1205]: No state is present for card sndrpihifiberry May 31 18:39:08 volumio-mackie volumio[1205]: info: Output device has changed, restarting MPD May 31 18:39:08 volumio-mackie systemd[1]: shairport-sync.service: Deactivated successfully. May 31 18:39:08 volumio-mackie systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:39:08 volumio-mackie systemd[1]: shairport-sync.service: Consumed 1.477s CPU time. May 31 18:39:08 volumio-mackie sudo[3249]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie sudo[3250]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie sudo[3168]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie volumio[1205]: info: Output device has changed, restarting Shairport Sync May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:08 volumio-mackie systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:39:08 volumio-mackie sudo[3261]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie sudo[3257]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 18:39:08 volumio-mackie volumio[1205]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:39:08 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:08 volumio-mackie sudo[3283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 18:39:08 volumio-mackie sudo[3277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 18:39:08 volumio-mackie sudo[3277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie sudo[3283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie sudo[3277]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie volumio[1205]: info: Preparing to generate the ALSA configuration file May 31 18:39:08 volumio-mackie systemd[1]: mpd.service: Deactivated successfully. May 31 18:39:08 volumio-mackie systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 18:39:08 volumio-mackie systemd[1]: mpd.socket: Deactivated successfully. May 31 18:39:08 volumio-mackie systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 18:39:08 volumio-mackie systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 18:39:08 volumio-mackie sudo[3302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:08 volumio-mackie sudo[3302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie sudo[3306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:08 volumio-mackie sudo[3306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie volumio[1205]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 18:39:08 volumio-mackie volumio[1205]: info: Reading ALSA contributions from plugins. May 31 18:39:08 volumio-mackie systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 18:39:08 volumio-mackie sudo[3314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 18:39:08 volumio-mackie systemd[1]: Starting mpd.service - Music Player Daemon... May 31 18:39:08 volumio-mackie volumio[1205]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 31 18:39:08 volumio-mackie volumio[1205]: info: MPD Permissions set May 31 18:39:08 volumio-mackie volumio[1205]: info: Shairport-Sync Started May 31 18:39:08 volumio-mackie volumio[1205]: info: Shairport-Sync Started May 31 18:39:08 volumio-mackie sudo[3314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie volumio[1205]: info: Executing endpoint qc_getconfig May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.583 [3274.3274] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.602 [3274.3274] INFO VolumeManager: [0xad4f48]: Setting new playback volume: 75 May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.602 [3274.3274] INFO VolumeManager: [0xad4f48]: Setting new mute state: 0 May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.602 [3274.3274] INFO AudioStreamManager: [0xad4ca0]: Setting new audio download buffer size: 1048576 May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.602 [3274.3274] INFO QobuzConnect: [0xad5810]: Client initialized! May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.602 [3274.3274] INFO SampleApp: Starting Avahi advertising, name: Volumio-Mackie, service name: _qobuz-connect._tcp May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie sudo[3314]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.670 [3274.3274] INFO LocalConfigManager: [0xad49c8]: Starting Local Configuration server May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.670 [3274.3274] INFO SampleApp: Starting Local configuration server May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.671 [3274.3274] INFO SampleApp: Connected to UNIX socket client 0xabf818 May 31 18:39:08 volumio-mackie sudo[3302]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie qobuz-connect[3274]: 20260531 18:39:08.734 [3274.3274] INFO SampleApp: Playback volume changed: 75 May 31 18:39:08 volumio-mackie sudo[3327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie sudo[3306]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie volumio[1205]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 31 18:39:08 volumio-mackie volumio[1205]: info: QobuzConnect: QOBUZ Connect daemon connected May 31 18:39:08 volumio-mackie sudo[3327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:39:08 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:08 volumio-mackie sudo[3315]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 18:39:08 volumio-mackie volumio[1205]: info: Starting Shairport Sync May 31 18:39:08 volumio-mackie sudo[3315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 18:39:08 volumio-mackie sudo[3315]: pam_unix(sudo:session): session closed for user root May 31 18:39:08 volumio-mackie volumio[1205]: info: Asound.conf file unchanged, so no further update is needed May 31 18:39:08 volumio-mackie sudo[3333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 18:39:08 volumio-mackie volumio[1205]: info: Output device has changed, restarting MPD May 31 18:39:08 volumio-mackie sudo[3333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie volumio[1205]: info: Output device has changed, restarting Shairport Sync May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:08 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:08 volumio-mackie systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 31 18:39:08 volumio-mackie systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 18:39:08 volumio-mackie volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 18:39:08 volumio-mackie sudo[3337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 18:39:08 volumio-mackie sudo[3337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:08 volumio-mackie systemd[1]: shairport-sync.service: Deactivated successfully. May 31 18:39:08 volumio-mackie systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:39:09 volumio-mackie sudo[3337]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie volumio[1205]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie qobuz-connect[3274]: 20260531 18:39:09.011 [3274.3274] INFO SampleApp: Stopping Local configuration server May 31 18:39:09 volumio-mackie systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 18:39:09 volumio-mackie sudo[3333]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie sudo[3339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 18:39:09 volumio-mackie sudo[3339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:09 volumio-mackie systemd[1]: mpd.service: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 18:39:09 volumio-mackie systemd[1]: mpd.socket: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 18:39:09 volumio-mackie systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 18:39:09 volumio-mackie volumio[1205]: info: QobuzConnect: setDeactiveState invoked May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::volumioGetState May 31 18:39:09 volumio-mackie volumio[1205]: info: CorePlayQueue::getTrack 0 May 31 18:39:09 volumio-mackie systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 18:39:09 volumio-mackie systemd[1]: Starting mpd.service - Music Player Daemon... May 31 18:39:09 volumio-mackie sudo[3366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:09 volumio-mackie sudo[3366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:09 volumio-mackie sudo[3368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 18:39:09 volumio-mackie sudo[3368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:09 volumio-mackie volumio[1205]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 31 18:39:09 volumio-mackie volumio[1205]: info: PLUGIN onReboot : networkfs May 31 18:39:09 volumio-mackie sudo[3376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 18:39:09 volumio-mackie sudo[3376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:09 volumio-mackie sudo[3376]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie sudo[3366]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie volumio[1205]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 31 18:39:09 volumio-mackie volumio[1205]: info: MPD Permissions set May 31 18:39:09 volumio-mackie volumio[1205]: info: Shairport-Sync Started May 31 18:39:09 volumio-mackie sudo[3368]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie sudo[3365]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 18:39:09 volumio-mackie sudo[3365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie sudo[3365]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie sudo[3381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 18:39:09 volumio-mackie sudo[3384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot May 31 18:39:09 volumio-mackie sudo[3381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:09 volumio-mackie sudo[3384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:09 volumio-mackie systemd-logind[690]: The system will reboot now! May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 18:39:09 volumio-mackie volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 18:39:09 volumio-mackie volumio[1205]: info: Starting Shairport Sync May 31 18:39:09 volumio-mackie sudo[3396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 18:39:09 volumio-mackie sudo[3396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 18:39:09 volumio-mackie systemd-logind[690]: System is rebooting. May 31 18:39:09 volumio-mackie sudo[3339]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie sudo[3209]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie volumio[1205]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service May 31 18:39:09 volumio-mackie volumio[1205]: Job for mpd.service canceled. May 31 18:39:09 volumio-mackie sudo[3384]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie sudo[3283]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie sudo[3327]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie sudo[3194]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie volumio[1205]: info: Rebooting May 31 18:39:09 volumio-mackie volumio[1205]: error: QobuzConnect: could not execute /bin/systemctl to restart qobuz connect process. Reason: Job for qobuz-connect.service canceled. May 31 18:39:09 volumio-mackie volumio[1205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 18:39:09 volumio-mackie volumio[1205]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "undefined".] { May 31 18:39:09 volumio-mackie volumio[1205]: code: 'ERR_UNHANDLED_REJECTION' May 31 18:39:09 volumio-mackie volumio[1205]: } May 31 18:39:09 volumio-mackie volumio[1205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 18:39:09 volumio-mackie systemd[1]: Requested transaction contradicts existing jobs: Transaction for qobuz-connect.service/restart is destructive (systemd-tmpfiles-setup-dev.service has 'stop' job queued, but 'start' is included in transaction). May 31 18:39:09 volumio-mackie sudo[3381]: pam_unix(sudo:session): session closed for user root May 31 18:39:09 volumio-mackie systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. May 31 18:39:09 volumio-mackie systemd[1]: Stopped target bluetooth.target - Bluetooth Support. May 31 18:39:09 volumio-mackie systemd[1]: Stopped target graphical.target - Graphical Interface. May 31 18:39:09 volumio-mackie systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 31 18:39:09 volumio-mackie systemd[1]: Stopped target rpc_pipefs.target. May 31 18:39:09 volumio-mackie systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. May 31 18:39:09 volumio-mackie systemd[1]: Stopped target timers.target - Timer Units. May 31 18:39:09 volumio-mackie systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. May 31 18:39:09 volumio-mackie systemd[1]: apt-daily.timer: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Stopped apt-daily.timer - Daily apt download activities. May 31 18:39:09 volumio-mackie systemd[1]: dpkg-db-backup.timer: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. May 31 18:39:09 volumio-mackie systemd[1]: e2scrub_all.timer: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. May 31 18:39:09 volumio-mackie systemd[1]: fstrim.timer: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. May 31 18:39:09 volumio-mackie systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. May 31 18:39:09 volumio-mackie systemd[1]: setdatetime-helper.timer: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. May 31 18:39:09 volumio-mackie systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 31 18:39:09 volumio-mackie systemd[1]: systemd-rfkill.socket: Deactivated successfully. May 31 18:39:09 volumio-mackie systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. May 31 18:39:10 volumio-mackie systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... May 31 18:39:10 volumio-mackie bluealsa[955]: ../src/ba-adapter.c:144: Freeing adapter: hci0 May 31 18:39:10 volumio-mackie systemd[1]: Stopping bluealsa.service - BlueALSA service... May 31 18:39:10 volumio-mackie systemd[1]: rpi-display-backlight.service - Turns off Raspberry Pi display backlight on shutdown/reboot was skipped because of an unmet condition check (ConditionPathIsDirectory=/proc/device-tree/rpi_backlight). May 31 18:39:10 volumio-mackie sshd[1626]: Received signal 15; terminating. May 31 18:39:10 volumio-mackie bluetoothd[830]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 May 31 18:39:10 volumio-mackie systemd[1]: Stopping ssh.service - OpenBSD Secure Shell server... May 31 18:39:10 volumio-mackie bluetoothd[830]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 May 31 18:39:10 volumio-mackie bluetoothd[830]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 May 31 18:39:10 volumio-mackie systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... May 31 18:39:10 volumio-mackie autossh[2801]: received signal to exit (15) May 31 18:39:10 volumio-mackie bluetoothd[830]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 May 31 18:39:10 volumio-mackie systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... May 31 18:39:10 volumio-mackie systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... May 31 18:39:10 volumio-mackie systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... May 31 18:39:10 volumio-mackie volumiobt[3413]: INFO [BTSTART] Disconnecting all Bluetooth devices... May 31 18:39:10 volumio-mackie systemd[1]: bluealsa.service: Deactivated successfully. May 31 18:39:10 volumio-mackie systemd[1]: Stopped bluealsa.service - BlueALSA service. May 31 18:39:10 volumio-mackie systemd[1]: ssh.service: Deactivated successfully. May 31 18:39:10 volumio-mackie systemd[1]: Stopped ssh.service - OpenBSD Secure Shell server. May 31 18:39:10 volumio-mackie systemd[1]: volumio5-onboarding.service: Deactivated successfully. May 31 18:39:10 volumio-mackie systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 31 18:39:10 volumio-mackie systemd[1]: volumio5-onboarding.service: Consumed 2.887s CPU time. May 31 18:39:10 volumio-mackie systemd[1]: sshtunnel.service: Deactivated successfully. May 31 18:39:10 volumio-mackie systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. May 31 18:39:10 volumio-mackie systemd[1]: mpd.service: Deactivated successfully. May 31 18:39:10 volumio-mackie systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 18:39:10 volumio-mackie systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. May 31 18:39:10 volumio-mackie systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. May 31 18:39:10 volumio-mackie systemd[1]: systemd-random-seed.service: Deactivated successfully. May 31 18:39:10 volumio-mackie systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. May 31 18:39:10 volumio-mackie systemd[1]: Requested transaction contradicts existing jobs: Transaction for shairport-sync.service/restart is destructive (var-spool-cups-tmp.mount has 'stop' job queued, but 'start' is included in transaction). May 31 18:39:10 volumio-mackie sudo[3396]: pam_unix(sudo:session): session closed for user root May 31 18:39:10 volumio-mackie bluetoothd[830]: Adv Monitor app :1.74 disconnected from D-Bus May 31 18:39:10 volumio-mackie volumiobt[3421]: Attempting to disconnect from 7F:B9:B3:98:CC:07 May 31 18:39:10 volumio-mackie volumiobt[3421]: [NEW] Media /org/bluez/hci0 May 31 18:39:10 volumio-mackie volumiobt[3421]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb May 31 18:39:10 volumio-mackie volumiobt[3421]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb May 31 18:39:10 volumio-mackie volumiobt[3421]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb May 31 18:39:10 volumio-mackie bluetoothd[830]: Path / reserved for Adv Monitor app :1.75 May 31 18:39:10 volumio-mackie volumiobt[3421]: AdvertisementMonitor path registered May 31 18:39:10 volumio-mackie qobuz-connect[3274]: 20260531 18:39:10.678 [3274.3274] INFO SampleApp: shat down connection on UNIX socket May 31 18:39:10 volumio-mackie systemd[1]: qobuz-connect.service: Deactivated successfully. May 31 18:39:10 volumio-mackie systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 31 18:39:10 volumio-mackie sudo[3424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 18:38' May 31 18:39:10 volumio-mackie sudo[3424]: 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"