Dec 19 12:10:38 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 19 12:10:38 volumio volumio[1060]: info: Setting timezone to Africa/Johannesburg Dec 19 12:10:38 volumio sudo[3070]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 19 12:10:38 volumio sudo[3070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:38 volumio sudo[3070]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:38 volumio sudo[3074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Africa/Johannesburg /etc/localtime Dec 19 12:10:38 volumio sudo[3074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:38 volumio sudo[3074]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:38 volumio sudo[3078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 19 12:10:38 volumio sudo[3078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:38 volumio sudo[3078]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:38 volumio sudo[3082]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Africa/Johannesburg Dec 19 12:10:38 volumio sudo[3082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:38 volumio dbus-daemon[794]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.27' (uid=0 pid=3083 comm="/usr/bin/timedatectl set-timezone Africa/Johannesb") Dec 19 12:10:38 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 19 12:10:38 volumio dbus-daemon[794]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 19 12:10:38 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 19 12:10:38 volumio sudo[3082]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:40 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 19 12:10:40 volumio volumio[1060]: info: Loading i18n strings for locale en Dec 19 12:10:40 volumio volumio[1060]: Updating browse sources language Dec 19 12:10:40 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 12:10:40 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 19 12:10:40 volumio volumio[1060]: info: Setting timezone to Africa/Johannesburg Dec 19 12:10:40 volumio sudo[3090]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 19 12:10:40 volumio sudo[3090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:40 volumio sudo[3090]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:40 volumio sudo[3094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Africa/Johannesburg /etc/localtime Dec 19 12:10:40 volumio sudo[3094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:40 volumio sudo[3094]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:40 volumio sudo[3098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 19 12:10:40 volumio sudo[3098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:40 volumio sudo[3098]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:40 volumio sudo[3103]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Africa/Johannesburg Dec 19 12:10:40 volumio sudo[3103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:40 volumio sudo[3103]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:40 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 19 12:10:40 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 19 12:10:40 volumio sudo[3107]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 19 12:10:40 volumio sudo[3107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:40 volumio sudo[3107]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:40 volumio volumio[1060]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 19 12:10:40 volumio volumio[1060]: wlan0 Interface doesn't support scanning. Dec 19 12:10:40 volumio volumio[1060]: info: Cannot use regular scanning, forcing with ap-force Dec 19 12:10:40 volumio sudo[3113]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 19 12:10:40 volumio sudo[3113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:40 volumio sudo[3113]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:40 volumio volumio[1060]: command failed: No such device (-19) Dec 19 12:10:40 volumio volumio[1060]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 19 12:10:40 volumio volumio[1060]: command failed: No such device (-19) Dec 19 12:10:40 volumio volumio[1060]: info: Fetching Streaming Services browse cache Dec 19 12:10:43 volumio volumio[1060]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 19 12:10:43 volumio volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Dec 19 12:10:43 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 12:10:47 volumio ntpd[942]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 19 12:10:57 volumio volumio[1060]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 19 12:10:57 volumio volumio[1060]: info: Received Get System Info Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 12:10:57 volumio volumio[1060]: info: Discovery: Getting this device information Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:10:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 12:10:57 volumio volumio-remote-updater[825]: Test mode disabled Dec 19 12:10:57 volumio volumio-remote-updater[825]: Alpha mode disabled Dec 19 12:10:57 volumio volumio-remote-updater[825]: Alpha legacy test mode disabled Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:10:57 volumio volumio[1060]: info: Retrieving Cloud Streaming UI Dec 19 12:10:57 volumio volumio[1060]: info: Getting Tidal Cloud Configuration Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:10:57 volumio volumio[1060]: info: Getting Qobuz Cloud Configuration Dec 19 12:10:57 volumio volumio[1060]: info: Asking plugin for UI Config Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:10:57 volumio volumio[1060]: info: Getting Spotify Cloud Configuration Dec 19 12:10:57 volumio volumio[1060]: info: Asking plugin for UI Config Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:10:57 volumio volumio[1060]: info: Saving Spotify Acccount Dec 19 12:10:57 volumio volumio[1060]: info: Got Tidal Cloud Configuration Dec 19 12:10:57 volumio volumio[1060]: info: Got it Dec 19 12:10:57 volumio volumio[1060]: info: Got it Dec 19 12:10:57 volumio volumio[1060]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:10:57 volumio volumio[1060]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 19 12:10:57 volumio volumio[1060]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 19 12:10:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 19 12:10:58 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 19 12:10:58 volumio volumio[1060]: info: Loading i18n strings for locale en Dec 19 12:10:58 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 19 12:10:58 volumio volumio[1060]: info: Setting timezone to Africa/Johannesburg Dec 19 12:10:58 volumio sudo[3185]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 19 12:10:58 volumio sudo[3185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:58 volumio sudo[3185]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:58 volumio sudo[3189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Africa/Johannesburg /etc/localtime Dec 19 12:10:58 volumio sudo[3189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:58 volumio sudo[3189]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:58 volumio sudo[3193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 19 12:10:58 volumio sudo[3193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:58 volumio sudo[3193]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:58 volumio sudo[3197]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Africa/Johannesburg Dec 19 12:10:58 volumio sudo[3197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:10:58 volumio sudo[3197]: pam_unix(sudo:session): session closed for user root Dec 19 12:10:58 volumio volumio[1060]: Updating browse sources language Dec 19 12:10:58 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 12:10:59 volumio volumio[1060]: info: Fetching Streaming Services browse cache Dec 19 12:11:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 19 12:11:00 volumio volumio[1060]: info: MyVolumio login type: Token Dec 19 12:11:01 volumio volumio[1060]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 19 12:11:02 volumio volumio[1060]: info: MyVolumio token set successfully Dec 19 12:11:02 volumio volumio[1060]: info: MYVOLUMIO: Adding device Dec 19 12:11:02 volumio volumio[1060]: info: MYVOLUMIO: Evaluating Server Dec 19 12:11:02 volumio volumio[1060]: info: MyVolumio Plan changed: premium Dec 19 12:11:02 volumio volumio[1060]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 19 12:11:02 volumio volumio[1060]: info: Removing browser output: myVolumio user plan is not superstar Dec 19 12:11:02 volumio volumio[1060]: info: Removing audio output: Dec 19 12:11:02 volumio volumio[1060]: info: MYVOLUMIO: Adding device Dec 19 12:11:02 volumio volumio[1060]: info: MYVOLUMIO: Evaluating Server Dec 19 12:11:02 volumio volumio[1060]: info: Remote config written successfully Dec 19 12:11:02 volumio volumio[1060]: info: Starting Tunnel 1 Dec 19 12:11:02 volumio volumio[1060]: info: Starting Tunnel Connection Checker Dec 19 12:11:02 volumio volumio[1060]: info: MYVolumio Device disabled Dec 19 12:11:02 volumio volumio[1060]: info: MyVolumio status changed Dec 19 12:11:02 volumio volumio[1060]: info: Streaming services startup Dec 19 12:11:02 volumio volumio[1060]: info: Starting Streaming Daemon Dec 19 12:11:02 volumio sudo[3256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 19 12:11:02 volumio sudo[3256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:02 volumio sudo[3256]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:02 volumio volumio[1060]: error: Cannot start Volumio Streaming Daemon Dec 19 12:11:02 volumio volumio[1060]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 19 12:11:02 volumio volumio[1060]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 19 12:11:03 volumio volumio[1060]: info: Setting Geolocation for MyVolumio to eu12 Dec 19 12:11:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:03 volumio volumio[1060]: info: Setting Geolocation for MyVolumio to eu3 Dec 19 12:11:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:03 volumio volumio[1060]: info: MYVolumio Device enabled Dec 19 12:11:03 volumio volumio[1060]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 19 12:11:03 volumio volumio[1060]: info: MyVolumio status changed Dec 19 12:11:03 volumio volumio[1060]: info: Streaming services startup Dec 19 12:11:03 volumio volumio[1060]: info: Starting Streaming Daemon Dec 19 12:11:03 volumio volumio[1060]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Dec 19 12:11:03 volumio volumio[1060]: info: Successfully Added MyVolumio device Dec 19 12:11:03 volumio sudo[3261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 19 12:11:03 volumio sudo[3261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:03 volumio sudo[3261]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:03 volumio volumio[1060]: error: Cannot start Volumio Streaming Daemon Dec 19 12:11:03 volumio volumio[1060]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 19 12:11:03 volumio volumio[1060]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 19 12:11:03 volumio volumio[1060]: info: Successfully Added MyVolumio device Dec 19 12:11:04 volumio volumio[1060]: info: Updating MyVolumio device info Dec 19 12:11:04 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:04 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:04 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:04 volumio volumio[1060]: info: Updating MyVolumio device info Dec 19 12:11:04 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:04 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:04 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:04 volumio volumio[1060]: info: Successfully Updated MyVolumio device Dec 19 12:11:05 volumio volumio[1060]: info: Successfully Updated MyVolumio device Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 19 12:11:05 volumio volumio[1060]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 19 12:11:05 volumio sudo[3267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 19 12:11:05 volumio sudo[3267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:05 volumio sudo[3267]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:05 volumio volumio[1060]: info: MRS: MultiRoom plugin initialized Dec 19 12:11:05 volumio volumio[1060]: info: MRS: STOPPING SNAPCLIENT Dec 19 12:11:05 volumio volumio[1060]: info: MRS: Snap server stop Dec 19 12:11:05 volumio volumio[1060]: info: MRS: STOPPING volumioStreaming Dec 19 12:11:05 volumio sudo[3284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 19 12:11:05 volumio sudo[3284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 19 12:11:05 volumio sudo[3286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 19 12:11:05 volumio sudo[3286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:05 volumio sudo[3290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 19 12:11:05 volumio sudo[3290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:05 volumio sudo[3292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 19 12:11:05 volumio sudo[3284]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:05 volumio sudo[3292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 19 12:11:05 volumio sudo[3292]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 19 12:11:05 volumio sudo[3286]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:05 volumio sudo[3290]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 19 12:11:05 volumio volumio[1060]: info: Preparing to generate the ALSA configuration file Dec 19 12:11:05 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 19 12:11:05 volumio volumio[1060]: info: MRS: Removed streaming files Dec 19 12:11:05 volumio volumio[1060]: info: MRS: volumioStreaming STOPPED Dec 19 12:11:05 volumio volumio[1060]: info: MRS: SNAPSERVER STOPPED Dec 19 12:11:05 volumio volumio[1060]: info: MRS: SNAPCLIENT STOPPED Dec 19 12:11:05 volumio volumio[1060]: info: Asound.conf file unchanged, so no further update is needed Dec 19 12:11:05 volumio volumio[1060]: info: Output device has changed, restarting MPD Dec 19 12:11:05 volumio volumio[1060]: info: Output device has changed, restarting Shairport Sync Dec 19 12:11:05 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:05 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:05 volumio sudo[3297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 12:11:05 volumio sudo[3297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:05 volumio sudo[3297]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:05 volumio sudo[3299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 12:11:05 volumio sudo[3299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:05 volumio volumio[1060]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 12:11:05 volumio volumio[1060]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 19 12:11:05 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:05 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 19 12:11:05 volumio volumio[1060]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 19 12:11:05 volumio volumio[1060]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 19 12:11:05 volumio volumio[1060]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 19 12:11:05 volumio volumio[1060]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 19 12:11:05 volumio volumio[1060]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 19 12:11:05 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 19 12:11:05 volumio volumio[1060]: info: MRS: Pushing multiroomSync output for this device Dec 19 12:11:05 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:05 volumio volumio[1060]: info: Adding audio output: Dec 19 12:11:05 volumio volumio[1060]: info: Adding audio output: Dec 19 12:11:05 volumio volumio[1060]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 19 12:11:05 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 19 12:11:05 volumio volumio[1060]: info: Adding METAVOLUMIO REST API Endpoints Dec 19 12:11:05 volumio volumio[1060]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 19 12:11:05 volumio volumio[1060]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 19 12:11:05 volumio volumio[1060]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 19 12:11:05 volumio volumio[1060]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 19 12:11:05 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 19 12:11:05 volumio volumio[1060]: info: Preparing CD Folders Dec 19 12:11:05 volumio volumio[1060]: info: Adding CD REST API Endpoints Dec 19 12:11:05 volumio volumio[1060]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 19 12:11:05 volumio volumio[1060]: info: Starting UDEV Watcher for CD Dec 19 12:11:05 volumio volumio[1060]: info: Detecting CD presence with UDEV Dec 19 12:11:05 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 19 12:11:05 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 19 12:11:05 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 19 12:11:05 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 19 12:11:05 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 19 12:11:05 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 19 12:11:05 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 12:11:05 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 12:11:05 volumio sudo[3319]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 12:11:05 volumio sudo[3319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 12:11:05 volumio sudo[3319]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio volumio[1060]: info: [cd-plugin] Set CD speed to 1X Dec 19 12:11:06 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 19 12:11:06 volumio volumio[1060]: info: Adding inputs REST Endpoints Dec 19 12:11:06 volumio volumio[1060]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 19 12:11:06 volumio volumio[1060]: info: Scanning Audio Inputs Dec 19 12:11:06 volumio volumio[1060]: info: Checking against Known Cards name Dec 19 12:11:06 volumio volumio[1060]: info: Checking against Known Cards name Dec 19 12:11:06 volumio volumio[1060]: info: Adding Server instance for streaming Dec 19 12:11:06 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 19 12:11:06 volumio volumio[1060]: error: Hi Res Audio Failed Login: Missing Login Data Dec 19 12:11:06 volumio volumio[1060]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 19 12:11:06 volumio volumio[1060]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 19 12:11:06 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 19 12:11:06 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 19 12:11:06 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 19 12:11:06 volumio volumio[1060]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 19 12:11:06 volumio volumio[1060]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 19 12:11:06 volumio sudo[3328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:06 volumio sudo[3328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 19 12:11:06 volumio sudo[3335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 19 12:11:06 volumio volumio[1060]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 19 12:11:06 volumio sudo[3335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio volumio[1060]: info: Adding TIDAL REST API Endpoints Dec 19 12:11:06 volumio volumio[1060]: info: Stopping AccessToken refresher cron for QOBUZ Dec 19 12:11:06 volumio sudo[3328]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio sudo[3335]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio sudo[3338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 19 12:11:06 volumio sudo[3338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio volumio[1060]: info: AccessToken refresher cron started for QOBUZ Dec 19 12:11:06 volumio volumio[1060]: info: Adding QOBUZ REST API Endpoints Dec 19 12:11:06 volumio volumio[1060]: info: MRS: Adding multiroomSync output Dec 19 12:11:06 volumio volumio[1060]: info: Adding audio output: Dec 19 12:11:06 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:06 volumio volumio[1060]: info: MRS: Adding multiroomSync output Dec 19 12:11:06 volumio volumio[1060]: info: Adding audio output: Dec 19 12:11:06 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:06 volumio volumio[1060]: info: MRS: Adding multiroomSync output Dec 19 12:11:06 volumio volumio[1060]: info: Adding audio output: Dec 19 12:11:06 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 19 12:11:06 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:06 volumio sudo[3338]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio volumio[1060]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Dec 19 12:11:06 volumio volumio[1060]: info: MPD Permissions set Dec 19 12:11:06 volumio sudo[3342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 19 12:11:06 volumio sudo[3342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 19 12:11:06 volumio sudo[3342]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 12:11:06 volumio volumiobt[3353]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::volumioStop Dec 19 12:11:06 volumio volumio[1060]: info: CoreStateMachine::stop Dec 19 12:11:06 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 12:11:06 volumio volumio[1060]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 19 12:11:06 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 12:11:06 volumio volumio[1060]: info: Starting Shairport Sync Dec 19 12:11:06 volumio sudo[3355]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 19 12:11:06 volumio sudo[3355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio sudo[3355]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio sudo[3359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 19 12:11:06 volumio sudo[3359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio sudo[3359]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio sudo[3358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 12:11:06 volumio sudo[3358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio volumiobt[3361]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 19 12:11:06 volumio volumio[1060]: info: Executing endpoint qc_getconfig Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 19 12:11:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 12:11:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 12:11:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:06 volumio systemd[1]: shairport-sync.service: Consumed 2.774s CPU time. Dec 19 12:11:06 volumio volumiobt[3369]: No default controller available Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.381 [3340.3340] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 19 12:11:06 volumio mpd[3323]: 2025-12-19T12:11:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 19 12:11:06 volumio volumio[1060]: info: MRS: Found cast device: MIBOX3-58c823c1af172e7e16cdef5b4678017a Dec 19 12:11:06 volumio volumio[1060]: info: Adding audio output: Dec 19 12:11:06 volumio volumio[1060]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 19 12:11:06 volumio volumio[1060]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 19 12:11:06 volumio volumio[1060]: info: Preparing to generate the ALSA configuration file Dec 19 12:11:06 volumio volumio[1060]: info: Asound.conf file unchanged, so no further update is needed Dec 19 12:11:06 volumio volumio[1060]: info: Output device has changed, restarting MPD Dec 19 12:11:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:06 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 19 12:11:06 volumio sudo[3358]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio sudo[3299]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio volumio[1060]: info: Output device has changed, restarting Shairport Sync Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:06 volumio sudo[3375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 12:11:06 volumio sudo[3375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio sudo[3377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 12:11:06 volumio sudo[3377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio sudo[3375]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio volumio[1060]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 12:11:06 volumio volumio[1060]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:06 volumio volumio[1060]: info: Preparing to generate the ALSA configuration file Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.444 [3340.3340] INFO VolumeManager: [0x55d66f81cb30]: Setting new playback volume: 75 Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.444 [3340.3340] INFO VolumeManager: [0x55d66f81cb30]: Setting new mute state: 0 Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.444 [3340.3340] INFO QobuzConnect: [0x55d66f81dd50]: Client initialized! Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.444 [3340.3340] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 19 12:11:06 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.450 [3340.3340] INFO LocalConfigManager: [0x55d66f81be20]: Starting Local Configuration server Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.450 [3340.3340] INFO SampleApp: Starting Local configuration server Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.451 [3340.3340] INFO SampleApp: Connected to UNIX socket client 0x55d66f7f2bb0 Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:06 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 19 12:11:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 19 12:11:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 19 12:11:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 19 12:11:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 19 12:11:06 volumio volumio[1060]: info: MPD Permissions set Dec 19 12:11:06 volumio volumio[1060]: info: Shairport-Sync Started Dec 19 12:11:06 volumio sudo[3407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 19 12:11:06 volumio sudo[3407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 12:11:06 volumio volumio[1060]: info: Asound.conf file unchanged, so no further update is needed Dec 19 12:11:06 volumio volumio[1060]: info: Output device has changed, restarting MPD Dec 19 12:11:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 12:11:06 volumio volumio[1060]: info: Output device has changed, restarting Shairport Sync Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:06 volumio sudo[3407]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio sudo[3421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 12:11:06 volumio sudo[3421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio sudo[3421]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio volumio[1060]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 12:11:06 volumio volumio[1060]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio sudo[3424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 12:11:06 volumio sudo[3424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio sudo[3427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 19 12:11:06 volumio sudo[3427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.532 [3340.3340] INFO SampleApp: Playback volume changed: 75 Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:06 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 19 12:11:06 volumio qobuz-connect[3340]: 20251219 12:11:06.546 [3340.3340] INFO SampleApp: Stopping Local configuration server Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:06 volumio sudo[3418]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 12:11:06 volumio sudo[3418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 12:11:06 volumio sudo[3418]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio volumio[1060]: info: MPD Permissions set Dec 19 12:11:06 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 19 12:11:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 19 12:11:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 19 12:11:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 19 12:11:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 19 12:11:06 volumio volumio[1060]: error: MPD error: Error: read ECONNRESET Dec 19 12:11:06 volumio volumio[1060]: info: Starting Shairport Sync Dec 19 12:11:06 volumio sudo[3438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 19 12:11:06 volumio sudo[3438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio sudo[3442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 12:11:06 volumio sudo[3442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 12:11:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:06 volumio volumio[1060]: info: Starting Shairport Sync Dec 19 12:11:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 12:11:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 12:11:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 12:11:06 volumio sudo[3438]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 12:11:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:06 volumio sudo[3454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 12:11:06 volumio sudo[3454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:06 volumio sudo[3442]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio volumio[1060]: info: Shairport-Sync Started Dec 19 12:11:06 volumio sudo[3455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 19 12:11:06 volumio sudo[3455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 12:11:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 12:11:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:06 volumio sudo[3454]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:06 volumio sudo[3453]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 12:11:06 volumio sudo[3453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 12:11:06 volumio volumio[1060]: info: Shairport-Sync Started Dec 19 12:11:06 volumio sudo[3453]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:07 volumio mpd[3461]: 2025-12-19T12:11:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 19 12:11:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 19 12:11:07 volumio sudo[3424]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:07 volumio sudo[3377]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:07 volumio volumio[1060]: error: MPD error: The expression evaluated to a falsy value: Dec 19 12:11:07 volumio volumio[1060]: assert.ok(self.idling) Dec 19 12:11:07 volumio volumio[1060]: error: The expression evaluated to a falsy value: Dec 19 12:11:07 volumio volumio[1060]: assert.ok(self.idling) Dec 19 12:11:07 volumio volumio[1060]: error: updateQueue error: null Dec 19 12:11:07 volumio volumiobt[3479]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 19 12:11:07 volumio volumiobt[3480]: [83B blob data] Dec 19 12:11:07 volumio volumiobt[3480]: No default controller available Dec 19 12:11:07 volumio volumiobt[3480]: [bluetoothctl]> pairable on Dec 19 12:11:07 volumio volumiobt[3480]: No default controller available Dec 19 12:11:07 volumio volumiobt[3480]: [bluetoothctl]> Dec 19 12:11:07 volumio volumiobt[3481]: INFO [BTSTART] Registering Bluetooth agent... Dec 19 12:11:07 volumio volumiobt[3483]: No agent is registered Dec 19 12:11:07 volumio volumiobt[3484]: INFO [BTSTART] Agent registered successfully. Dec 19 12:11:07 volumio volumiobt[3485]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 19 12:11:07 volumio sudo[3493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 19 12:11:07 volumio sudo[3493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:07 volumio 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. Dec 19 12:11:07 volumio 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. Dec 19 12:11:07 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 19 12:11:07 volumio volumio[1060]: info: Remote SSH Started Dec 19 12:11:07 volumio sudo[3493]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:07 volumio autossh[3496]: port set to 0, monitoring disabled Dec 19 12:11:07 volumio autossh[3496]: starting ssh (count 1) Dec 19 12:11:07 volumio autossh[3496]: ssh child pid is 3499 Dec 19 12:11:07 volumio volumio[1060]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 19 12:11:07 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:07 volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:07 volumio volumiobt[3486]: INFO:a2dp-agent:Connecting to system bus. Dec 19 12:11:07 volumio volumiobt[3486]: ERROR:a2dp-agent:No Bluetooth adapter found: Bluetooth adapter not found Dec 19 12:11:07 volumio volumiobt[3502]: INFO [BTSTART] Disconnecting all Bluetooth devices... Dec 19 12:11:07 volumio volumiobt[3507]: INFO [BTSTART] Unregistering Bluetooth agent... Dec 19 12:11:07 volumio volumiobt[3508]: No agent is registered Dec 19 12:11:07 volumio volumiobt[3509]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Dec 19 12:11:08 volumio volumiobt[3511]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Dec 19 12:11:08 volumio systemd[1]: volumiobt.service: Deactivated successfully. Dec 19 12:11:08 volumio qobuz-connect[3340]: 20251219 12:11:08.461 [3340.3340] INFO SampleApp: shat down connection on UNIX socket Dec 19 12:11:08 volumio volumio[1060]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 19 12:11:08 volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:08 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:08 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 19 12:11:08 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 19 12:11:08 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 19 12:11:08 volumio sudo[3427]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:08 volumio sudo[3455]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:08 volumio volumio[1060]: info: Executing endpoint qc_getconfig Dec 19 12:11:08 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 19 12:11:08 volumio qobuz-connect[3516]: 20251219 12:11:08.511 [3516.3516] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 19 12:11:08 volumio qobuz-connect[3516]: 20251219 12:11:08.513 [3516.3516] INFO VolumeManager: [0x5627fa691b30]: Setting new playback volume: 75 Dec 19 12:11:08 volumio qobuz-connect[3516]: 20251219 12:11:08.513 [3516.3516] INFO VolumeManager: [0x5627fa691b30]: Setting new mute state: 0 Dec 19 12:11:08 volumio qobuz-connect[3516]: 20251219 12:11:08.513 [3516.3516] INFO QobuzConnect: [0x5627fa692d50]: Client initialized! Dec 19 12:11:08 volumio qobuz-connect[3516]: 20251219 12:11:08.513 [3516.3516] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 19 12:11:08 volumio volumio[1060]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 19 12:11:08 volumio volumio[1060]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 19 12:11:08 volumio qobuz-connect[3516]: 20251219 12:11:08.517 [3516.3516] INFO LocalConfigManager: [0x5627fa690e20]: Starting Local Configuration server Dec 19 12:11:08 volumio qobuz-connect[3516]: 20251219 12:11:08.517 [3516.3516] INFO SampleApp: Starting Local configuration server Dec 19 12:11:08 volumio qobuz-connect[3516]: 20251219 12:11:08.518 [3516.3516] INFO SampleApp: Connected to UNIX socket client 0x5627fa667bb0 Dec 19 12:11:08 volumio volumiossh-tunnel[3499]: Warning: Permanently added '[eu3.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 19 12:11:08 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 19 12:11:08 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 19 12:11:08 volumio qobuz-connect[3516]: 20251219 12:11:08.662 [3516.3516] INFO SampleApp: Playback volume changed: 75 Dec 19 12:11:08 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:08 volumio volumio-remote-updater[825]: Test mode disabled Dec 19 12:11:08 volumio volumio-remote-updater[825]: Alpha mode disabled Dec 19 12:11:08 volumio volumio-remote-updater[825]: Alpha legacy test mode disabled Dec 19 12:11:08 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 19 12:11:09 volumio volumio[1060]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 19 12:11:09 volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:09 volumio volumio[1060]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:09 volumio volumio[1060]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:09 volumio sudo[3533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 12:11:09 volumio sudo[3533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:09 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 19 12:11:09 volumio sudo[3533]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:09 volumio volumio[1060]: info: Executing endpoint tc_getconfig Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 19 12:11:09 volumio vtcs[3536]: STARTING TidalConnect services, version: 1.5.2.56 Dec 19 12:11:09 volumio vtcs[3536]: STARTED TidalConnect services. Dec 19 12:11:09 volumio volumio[1060]: info: Executing endpoint tc_connect Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 19 12:11:09 volumio volumio[1060]: info: Connecting to TidalConnect Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:09 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 19 12:11:09 volumio volumio[1060]: 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} Dec 19 12:11:09 volumio volumio[1060]: verbose: CURRENT POSITION 0 Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:09 volumio volumio[1060]: info: No code Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:09 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 19 12:11:09 volumio volumio[1060]: 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} Dec 19 12:11:09 volumio volumio[1060]: verbose: CURRENT POSITION 0 Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:09 volumio volumio[1060]: info: No code Dec 19 12:11:09 volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:09 volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:09 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:09 volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:09 volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:09 volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:09 volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:09 volumio sudo[3553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 12:11:09 volumio sudo[3553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:09 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 12:11:09 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 12:11:09 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:09 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:09 volumio sudo[3553]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:09 volumio mpd_monitor.sh[3555]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 12:11:09 volumio volumio[1060]: info: Successfully started MPD Monitor Dec 19 12:11:10 volumio sudo[3561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 12:11:10 volumio sudo[3561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:10 volumio sudo[3563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 12:11:10 volumio sudo[3563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:10 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 12:11:10 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 12:11:10 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:10 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:10 volumio sudo[3561]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:10 volumio mpd_monitor.sh[3566]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 12:11:10 volumio volumio[1060]: info: Successfully started MPD Monitor Dec 19 12:11:10 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 12:11:10 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 12:11:10 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:10 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:10 volumio sudo[3563]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:10 volumio volumio[1060]: info: Successfully started MPD Monitor Dec 19 12:11:10 volumio mpd_monitor.sh[3569]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 12:11:10 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 19 12:11:10 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 19 12:11:10 volumio volumio-remote-updater[825]: Test mode disabled Dec 19 12:11:10 volumio volumio-remote-updater[825]: Alpha mode disabled Dec 19 12:11:10 volumio volumio-remote-updater[825]: Alpha legacy test mode disabled Dec 19 12:11:10 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 19 12:11:10 volumio volumio[1060]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 19 12:11:10 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 19 12:11:10 volumio volumio[1060]: info: MRS: Getting audio outputs on start Dec 19 12:11:10 volumio volumio[1060]: info: MRS: Requesting all other devices output Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 19 12:11:11 volumio volumio[1060]: info: System name has changed, restarting Shairport Sync Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:11 volumio volumio[1060]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 12:11:11 volumio volumio[1060]: info: Updating MyVolumio device info Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:11 volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:11 volumio vtcs[3536]: [2025-12-19 12:11:11.051] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 19 12:11:11 volumio vtcs[3536]: [2025-12-19 12:11:11.052] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Dec 19 12:11:11 volumio vtcs[3536]: [2025-12-19 12:11:11.055] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 19 12:11:11 volumio volumio[1060]: info: Discovery: Restarting Advertising due to device name change Dec 19 12:11:11 volumio sudo[3582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:11 volumio sudo[3582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:11 volumio sudo[3588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:11 volumio sudo[3588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:11 volumio sudo[3583]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 19 12:11:11 volumio sudo[3583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:11 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 19 12:11:11 volumio sudo[3583]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:11 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 19 12:11:11 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 19 12:11:11 volumio sudo[3595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 19 12:11:11 volumio sudo[3582]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:11 volumio sudo[3595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:11 volumio sudo[3588]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 19 12:11:11 volumio sudo[3595]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:11 volumio sudo[3602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 19 12:11:11 volumio sudo[3602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:11 volumio volumio[1060]: info: Starting Shairport Sync Dec 19 12:11:11 volumio sudo[3604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 19 12:11:11 volumio sudo[3604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:11 volumio sudo[3607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 12:11:11 volumio qobuz-connect[3516]: 20251219 12:11:11.145 [3516.3516] INFO SampleApp: Stopping Local configuration server Dec 19 12:11:11 volumio sudo[3607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:11 volumio sudo[3604]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:11 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 19 12:11:11 volumio volumio[1060]: info: Permissions for /etc/hosts set Dec 19 12:11:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 12:11:11 volumio volumio[1060]: info: Hostname now is mac-mini-volumio Dec 19 12:11:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 12:11:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:11 volumio systemd[1]: shairport-sync.service: Consumed 1.548s CPU time. Dec 19 12:11:11 volumio sudo[3611]: volumio : unable to resolve host volumio: System error Dec 19 12:11:11 volumio sudo[3611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname mac-mini-volumio Dec 19 12:11:11 volumio sudo[3611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:11 mac-mini-volumio sudo[3611]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:11 mac-mini-volumio volumio[1060]: info: New hostname set Dec 19 12:11:11 mac-mini-volumio sudo[3614]: volumio : unable to resolve host volumio: System error Dec 19 12:11:11 mac-mini-volumio sudo[3614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 19 12:11:11 mac-mini-volumio sudo[3614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:11 mac-mini-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:11 mac-mini-volumio sudo[3607]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:11 mac-mini-volumio volumio[1060]: info: Shairport-Sync Started Dec 19 12:11:11 mac-mini-volumio sudo[3614]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:11 mac-mini-volumio volumio[1060]: info: Permissions for /etc/avahi/services/volumio.service Dec 19 12:11:11 mac-mini-volumio volumio[1060]: info: Avahi name changed to mac-mini-volumio Dec 19 12:11:11 mac-mini-volumio avahi-daemon[793]: Files changed, reloading. Dec 19 12:11:11 mac-mini-volumio avahi-daemon[793]: Loading service file /services/volumio.service. Dec 19 12:11:11 mac-mini-volumio volumio[1060]: info: Successfully Updated MyVolumio device Dec 19 12:11:11 mac-mini-volumio volumio[1060]: error: updateQueue error: null Dec 19 12:11:12 mac-mini-volumio avahi-daemon[793]: Service "Mac Mini Volumio" (/services/volumio.service) successfully established. Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: Discovery: A device disappeared from network Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: Discovery: Device volumio disappeared from network Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: Discovery: A device disappeared from network Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: TidalConnect service started! Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3516]: 20251219 12:11:12.526 [3516.3516] INFO SampleApp: shat down connection on UNIX socket Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:12 mac-mini-volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 19 12:11:12 mac-mini-volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 19 12:11:12 mac-mini-volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 19 12:11:12 mac-mini-volumio sudo[3602]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: Executing endpoint qc_getconfig Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:12.575 [3646.3646] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:12.577 [3646.3646] INFO VolumeManager: [0x55b1f69d9b30]: Setting new playback volume: 75 Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:12.577 [3646.3646] INFO VolumeManager: [0x55b1f69d9b30]: Setting new mute state: 0 Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:12.577 [3646.3646] INFO QobuzConnect: [0x55b1f69dad50]: Client initialized! Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:12.577 [3646.3646] INFO SampleApp: Starting Avahi advertising, name: Mac Mini Volumio, service name: _qobuz-connect._tcp Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:12.581 [3646.3646] INFO LocalConfigManager: [0x55b1f69d8e20]: Starting Local Configuration server Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:12.582 [3646.3646] INFO SampleApp: Starting Local configuration server Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:12.582 [3646.3646] INFO SampleApp: Connected to UNIX socket client 0x55b1f69afbb0 Dec 19 12:11:12 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:12.725 [3646.3646] INFO SampleApp: Playback volume changed: 75 Dec 19 12:11:12 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:14 mac-mini-volumio sudo[3664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 12:11:14 mac-mini-volumio sudo[3664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:14 mac-mini-volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 19 12:11:14 mac-mini-volumio sudo[3664]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: Executing endpoint tc_getconfig Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 19 12:11:14 mac-mini-volumio vtcs[3667]: STARTING TidalConnect services, version: 1.5.2.56 Dec 19 12:11:14 mac-mini-volumio vtcs[3667]: STARTED TidalConnect services. Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: Executing endpoint tc_connect Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: Connecting to TidalConnect Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::servicePushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 19 12:11:14 mac-mini-volumio volumio[1060]: 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} Dec 19 12:11:14 mac-mini-volumio volumio[1060]: verbose: CURRENT POSITION 0 Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: No code Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::servicePushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 19 12:11:14 mac-mini-volumio volumio[1060]: 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} Dec 19 12:11:14 mac-mini-volumio volumio[1060]: verbose: CURRENT POSITION 0 Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: No code Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:14 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:14 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:14 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:14 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:14 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:14 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:14 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:16 mac-mini-volumio volumio[1060]: info: Discovery: Started advertising with name: Mac Mini Volumio Dec 19 12:11:16 mac-mini-volumio volumio[1060]: compat.c: read() failed: Resource temporarily unavailable Dec 19 12:11:16 mac-mini-volumio volumio[1060]: info: Discovery: mDNS temporarily unavailable during network transition Dec 19 12:11:17 mac-mini-volumio volumio[1060]: info: TidalConnect service started! Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: Preparing to save Alsa Options, stopping services first Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0,0","label":"Analog Out"},"disallowPush":true} Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: Found match in Cards Database: setting mixer for card Analog Out Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:18 mac-mini-volumio vtcs[3667]: [2025-12-19 12:11:18.947] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 19 12:11:18 mac-mini-volumio vtcs[3667]: [2025-12-19 12:11:18.949] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Dec 19 12:11:18 mac-mini-volumio vtcs[3667]: [2025-12-19 12:11:18.949] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Dec 19 12:11:18 mac-mini-volumio vtcs[3667]: [2025-12-19 12:11:18.949] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 19 12:11:18 mac-mini-volumio sudo[3696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:18 mac-mini-volumio sudo[3696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: Setting mixer Master for card Analog Out Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:18 mac-mini-volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 19 12:11:18 mac-mini-volumio systemd[1]: vtcs.service: Killing process 3674 (vtcs) with signal SIGKILL. Dec 19 12:11:18 mac-mini-volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 19 12:11:18 mac-mini-volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: Disabling external Volume Control Dec 19 12:11:18 mac-mini-volumio sudo[3702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:18 mac-mini-volumio sudo[3702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:18 mac-mini-volumio sudo[3696]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:11:18 mac-mini-volumio volumio[1060]: info: Preparing to generate the ALSA configuration file Dec 19 12:11:18 mac-mini-volumio sudo[3702]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio sudo[3712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:19 mac-mini-volumio sudo[3712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:19 mac-mini-volumio sudo[3715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:19 mac-mini-volumio sudo[3715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:19 mac-mini-volumio sudo[3723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 19 12:11:19 mac-mini-volumio sudo[3723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio sudo[3712]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Retrieving Cloud Streaming UI Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Reading ALSA contributions from plugins. Dec 19 12:11:19 mac-mini-volumio sudo[3730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 19 12:11:19 mac-mini-volumio sudo[3730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio sudo[3715]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio sudo[3723]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio sudo[3730]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: VolumeController:: Volume=42 Mute =false Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:19 mac-mini-volumio sudo[3735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 19 12:11:19 mac-mini-volumio sudo[3735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Getting Tidal Cloud Configuration Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:11:19 mac-mini-volumio sudo[3736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Getting Qobuz Cloud Configuration Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Asking plugin for UI Config Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:11:19 mac-mini-volumio sudo[3736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Getting Spotify Cloud Configuration Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Asking plugin for UI Config Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Saving Spotify Acccount Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Got it Dec 19 12:11:19 mac-mini-volumio volumio[1060]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 19 12:11:19 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:19.081 [3646.3646] INFO SampleApp: Stopping Local configuration server Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 19 12:11:19 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Got it Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Got Tidal Cloud Configuration Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Asound.conf file written Dec 19 12:11:19 mac-mini-volumio sudo[3747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 19 12:11:19 mac-mini-volumio sudo[3747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio sudo[3747]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio volumio[1060]: No state is present for card PCH Dec 19 12:11:19 mac-mini-volumio volumio[1060]: Found hardware: "HDA-Intel" "Cirrus Logic Generic" "HDA:10134208,106b6c00,00100300" "0x8086" "0x7270" Dec 19 12:11:19 mac-mini-volumio volumio[1060]: Hardware is initialized using a generic method Dec 19 12:11:19 mac-mini-volumio volumio[1060]: No state is present for card PCH Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Output device has changed, restarting MPD Dec 19 12:11:19 mac-mini-volumio sudo[3753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 12:11:19 mac-mini-volumio sudo[3753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Output device has changed, restarting Shairport Sync Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:19 mac-mini-volumio sudo[3753]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio sudo[3757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 12:11:19 mac-mini-volumio sudo[3757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Output device has changed, restarting MPD Dec 19 12:11:19 mac-mini-volumio sudo[3765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:19 mac-mini-volumio sudo[3765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio systemd[1]: mpd.service: Deactivated successfully. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 19 12:11:19 mac-mini-volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 19 12:11:19 mac-mini-volumio sudo[3767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 12:11:19 mac-mini-volumio sudo[3767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio sudo[3767]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Output device has changed, restarting Shairport Sync Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:19 mac-mini-volumio sudo[3770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 12:11:19 mac-mini-volumio sudo[3770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:19 mac-mini-volumio systemd[1]: mpd.service: Deactivated successfully. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 19 12:11:19 mac-mini-volumio sudo[3782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:19 mac-mini-volumio sudo[3782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio sudo[3784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:19 mac-mini-volumio sudo[3784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio sudo[3765]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 19 12:11:19 mac-mini-volumio sudo[3789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 19 12:11:19 mac-mini-volumio sudo[3789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:19 mac-mini-volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 12:11:19 mac-mini-volumio sudo[3800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 19 12:11:19 mac-mini-volumio sudo[3800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: MPD Permissions set Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: MPD Permissions set Dec 19 12:11:19 mac-mini-volumio sudo[3800]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio sudo[3784]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio sudo[3782]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio sudo[3796]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 12:11:19 mac-mini-volumio sudo[3796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 12:11:19 mac-mini-volumio sudo[3789]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio sudo[3805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 19 12:11:19 mac-mini-volumio sudo[3796]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio sudo[3805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio sudo[3806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 19 12:11:19 mac-mini-volumio sudo[3806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio sudo[3805]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 12:11:19 mac-mini-volumio sudo[3827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 19 12:11:19 mac-mini-volumio sudo[3827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Starting Shairport Sync Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Starting Shairport Sync Dec 19 12:11:19 mac-mini-volumio sudo[3835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 12:11:19 mac-mini-volumio sudo[3835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio sudo[3833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 12:11:19 mac-mini-volumio sudo[3833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 12:11:19 mac-mini-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:19 mac-mini-volumio systemd[1]: shairport-sync.service: Consumed 1.557s CPU time. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:19 mac-mini-volumio sudo[3833]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Shairport-Sync Started Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 12:11:19 mac-mini-volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:19 mac-mini-volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 12:11:19 mac-mini-volumio sudo[3835]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio volumio[1060]: info: Shairport-Sync Started Dec 19 12:11:19 mac-mini-volumio mpd[3813]: 2025-12-19T12:11:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 19 12:11:19 mac-mini-volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 19 12:11:19 mac-mini-volumio sudo[3770]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio sudo[3757]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:19 mac-mini-volumio volumio[1060]: error: MPD error: The expression evaluated to a falsy value: Dec 19 12:11:19 mac-mini-volumio volumio[1060]: assert.ok(self.idling) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: error: The expression evaluated to a falsy value: Dec 19 12:11:19 mac-mini-volumio volumio[1060]: assert.ok(self.idling) Dec 19 12:11:19 mac-mini-volumio volumio[1060]: error: updateQueue error: null Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3646]: 20251219 12:11:20.588 [3646.3646] INFO SampleApp: shat down connection on UNIX socket Dec 19 12:11:20 mac-mini-volumio volumio[1060]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 19 12:11:20 mac-mini-volumio volumio[1060]: info: QobuzConnect: setDeactiveState invoked Dec 19 12:11:20 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:20 mac-mini-volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 19 12:11:20 mac-mini-volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 19 12:11:20 mac-mini-volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 19 12:11:20 mac-mini-volumio sudo[3827]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:20 mac-mini-volumio sudo[3736]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:20 mac-mini-volumio sudo[3806]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:20 mac-mini-volumio sudo[3735]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:20 mac-mini-volumio volumio[1060]: info: Executing endpoint qc_getconfig Dec 19 12:11:20 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3857]: 20251219 12:11:20.646 [3857.3857] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 19 12:11:20 mac-mini-volumio volumio[1060]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 19 12:11:20 mac-mini-volumio volumio[1060]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3857]: 20251219 12:11:20.649 [3857.3857] INFO VolumeManager: [0x55c274e33b30]: Setting new playback volume: 75 Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3857]: 20251219 12:11:20.649 [3857.3857] INFO VolumeManager: [0x55c274e33b30]: Setting new mute state: 0 Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3857]: 20251219 12:11:20.649 [3857.3857] INFO QobuzConnect: [0x55c274e34d50]: Client initialized! Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3857]: 20251219 12:11:20.649 [3857.3857] INFO SampleApp: Starting Avahi advertising, name: Mac Mini Volumio, service name: _qobuz-connect._tcp Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3857]: 20251219 12:11:20.657 [3857.3857] INFO LocalConfigManager: [0x55c274e32e20]: Starting Local Configuration server Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3857]: 20251219 12:11:20.657 [3857.3857] INFO SampleApp: Starting Local configuration server Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3857]: 20251219 12:11:20.658 [3857.3857] INFO SampleApp: Connected to UNIX socket client 0x55c274e09bb0 Dec 19 12:11:20 mac-mini-volumio qobuz-connect[3857]: 20251219 12:11:20.796 [3857.3857] INFO SampleApp: Playback volume changed: 75 Dec 19 12:11:20 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:21 mac-mini-volumio sudo[3865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Dec 19 12:11:21 mac-mini-volumio sudo[3865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:21 mac-mini-volumio sudo[3867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Dec 19 12:11:21 mac-mini-volumio sudo[3867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:21 mac-mini-volumio sudo[3867]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:21 mac-mini-volumio volumio[1060]: info: Internal perms successfully set Dec 19 12:11:21 mac-mini-volumio sudo[3865]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:21 mac-mini-volumio volumio[1060]: info: smb.conf Permissions set Dec 19 12:11:21 mac-mini-volumio sudo[3871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Dec 19 12:11:21 mac-mini-volumio sudo[3871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:21 mac-mini-volumio systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Dec 19 12:11:21 mac-mini-volumio nmbd[1049]: [2025/12/19 12:11:21.097086, 0] ../../source3/nmbd/nmbd.c:59(terminate) Dec 19 12:11:21 mac-mini-volumio nmbd[1049]: Got SIGTERM: going down... Dec 19 12:11:21 mac-mini-volumio systemd[1]: nmbd.service: Deactivated successfully. Dec 19 12:11:21 mac-mini-volumio systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Dec 19 12:11:21 mac-mini-volumio sudo[3875]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 12:11:21 mac-mini-volumio sudo[3875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:21 mac-mini-volumio sudo[3875]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:21 mac-mini-volumio sudo[3877]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 12:11:21 mac-mini-volumio sudo[3877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:21 mac-mini-volumio systemd[1]: Starting nmbd.service - Samba NMB Daemon... Dec 19 12:11:21 mac-mini-volumio sudo[3880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 19 12:11:21 mac-mini-volumio sudo[3880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:21 mac-mini-volumio sudo[3877]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:21 mac-mini-volumio sudo[3880]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:21 mac-mini-volumio volumio[1060]: info: Upmpdcli Daemon Started Dec 19 12:11:21 mac-mini-volumio nmbd[3888]: [2025/12/19 12:11:21.268242, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 19 12:11:21 mac-mini-volumio nmbd[3888]: nmbd version 4.17.12-Debian started. Dec 19 12:11:21 mac-mini-volumio nmbd[3888]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 19 12:11:21 mac-mini-volumio nmbd[3888]: [2025/12/19 12:11:21.269734, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 19 12:11:21 mac-mini-volumio nmbd[3888]: started asyncdns process 3889 Dec 19 12:11:21 mac-mini-volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 19 12:11:21 mac-mini-volumio sudo[3871]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:21 mac-mini-volumio sudo[3891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Dec 19 12:11:21 mac-mini-volumio sudo[3891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:21 mac-mini-volumio systemd[1]: Stopping smbd.service - Samba SMB Daemon... Dec 19 12:11:21 mac-mini-volumio systemd[1]: smbd.service: Deactivated successfully. Dec 19 12:11:21 mac-mini-volumio systemd[1]: Stopped smbd.service - Samba SMB Daemon. Dec 19 12:11:21 mac-mini-volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 19 12:11:21 mac-mini-volumio smbd[3896]: [2025/12/19 12:11:21.400683, 0] ../../source3/smbd/server.c:1741(main) Dec 19 12:11:21 mac-mini-volumio smbd[3896]: smbd version 4.17.12-Debian started. Dec 19 12:11:21 mac-mini-volumio smbd[3896]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 19 12:11:21 mac-mini-volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 19 12:11:21 mac-mini-volumio sudo[3891]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:21 mac-mini-volumio volumio[1060]: info: SAMBA Restarted Dec 19 12:11:21 mac-mini-volumio sudo[3901]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 12:11:21 mac-mini-volumio sudo[3901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:21 mac-mini-volumio sudo[3901]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:21 mac-mini-volumio sudo[3903]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 12:11:21 mac-mini-volumio sudo[3903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:21 mac-mini-volumio sudo[3903]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:21 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:21 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:22 mac-mini-volumio sudo[3918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 12:11:22 mac-mini-volumio sudo[3918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:22 mac-mini-volumio sudo[3930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 12:11:22 mac-mini-volumio sudo[3930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:22 mac-mini-volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 19 12:11:22 mac-mini-volumio sudo[3918]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:22 mac-mini-volumio sudo[3930]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Executing endpoint tc_getconfig Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 19 12:11:22 mac-mini-volumio vtcs[3932]: STARTING TidalConnect services, version: 1.5.2.56 Dec 19 12:11:22 mac-mini-volumio vtcs[3932]: STARTED TidalConnect services. Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Executing endpoint tc_connect Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Connecting to TidalConnect Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::servicePushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 19 12:11:22 mac-mini-volumio volumio[1060]: 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} Dec 19 12:11:22 mac-mini-volumio volumio[1060]: verbose: CURRENT POSITION 0 Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: No code Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::servicePushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Dec 19 12:11:22 mac-mini-volumio volumio[1060]: 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} Dec 19 12:11:22 mac-mini-volumio volumio[1060]: verbose: CURRENT POSITION 0 Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: No code Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:22 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:22 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:22 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:22 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:22 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: TidalConnect service stoped! Dec 19 12:11:22 mac-mini-volumio sudo[3958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 12:11:22 mac-mini-volumio sudo[3958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:22 mac-mini-volumio sudo[3958]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 12:11:22 mac-mini-volumio sudo[3970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 12:11:22 mac-mini-volumio sudo[3970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:22 mac-mini-volumio sudo[3970]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:22 mac-mini-volumio sudo[3988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 12:11:22 mac-mini-volumio sudo[3988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:22 mac-mini-volumio sudo[3990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 12:11:22 mac-mini-volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 12:11:22 mac-mini-volumio sudo[3990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 12:11:22 mac-mini-volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 12:11:22 mac-mini-volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:22 mac-mini-volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:22 mac-mini-volumio mpd_monitor.sh[3993]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 12:11:22 mac-mini-volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 12:11:22 mac-mini-volumio sudo[3988]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:22 mac-mini-volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 12:11:22 mac-mini-volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:22 mac-mini-volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Successfully started MPD Monitor Dec 19 12:11:22 mac-mini-volumio sudo[3990]: pam_unix(sudo:session): session closed for user root Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: Successfully started MPD Monitor Dec 19 12:11:22 mac-mini-volumio mpd_monitor.sh[3996]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::getcurrentVolume Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioRetrievevolume Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: VolumeController:: Volume=42 Mute =false Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreStateMachine::updateTrackBlock Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CorePlayQueue::getTrackBlock Dec 19 12:11:22 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioRetrievevolume Dec 19 12:11:23 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:23 mac-mini-volumio volumio[1060]: info: VolumeController:: Volume=42 Mute =false Dec 19 12:11:23 mac-mini-volumio volumio[1060]: info: CoreStateMachine::pushState Dec 19 12:11:23 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Dec 19 12:11:23 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Dec 19 12:11:23 mac-mini-volumio volumio[1060]: info: MRS: Pushing multiroomSync output Dec 19 12:11:23 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Dec 19 12:11:23 mac-mini-volumio volumio[1060]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dykuNvIZfHhOuvKhS4hrxeIfYHM2.08e8ecc26a8f42aa2ff4ca5a5fc14a01.state.status' Dec 19 12:11:25 mac-mini-volumio volumio[1060]: info: TidalConnect service started! Dec 19 12:11:25 mac-mini-volumio volumio[1060]: info: TidalConnect service started! Dec 19 12:11:25 mac-mini-volumio volumio[1060]: info: TidalConnect service started! Dec 19 12:11:25 mac-mini-volumio volumio[1060]: info: TidalConnect service started! Dec 19 12:11:25 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 19 12:11:25 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 19 12:11:25 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 19 12:11:25 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 19 12:11:25 mac-mini-volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 19 12:11:26 mac-mini-volumio volumio[1060]: info: Discovery: Started advertising with name: Mac Mini Volumio Dec 19 12:11:26 mac-mini-volumio volumio[1060]: compat.c: read() failed: Resource temporarily unavailable Dec 19 12:11:26 mac-mini-volumio volumio[1060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 12:11:26 mac-mini-volumio volumio[1060]: Error: dns service error: unknown Dec 19 12:11:26 mac-mini-volumio volumio[1060]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 19 12:11:26 mac-mini-volumio volumio[1060]: errorCode: -65537 Dec 19 12:11:26 mac-mini-volumio volumio[1060]: } Dec 19 12:11:26 mac-mini-volumio volumio[1060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 12:11:26 mac-mini-volumio sudo[4033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-19 12:10' Dec 19 12:11:26 mac-mini-volumio sudo[4033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"