Sep 16 19:00:33 volumio sudo[2371]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 19:00:33 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:33 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:33 volumio sudo[2373]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 19:00:33 volumio sudo[2373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:33 volumio sudo[2373]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:34 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 16 19:00:34 volumio dbus-daemon[927]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.26' (uid=0 pid=2377 comm="timedatectl show --property=NTPSynchronized --valu") Sep 16 19:00:34 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 16 19:00:34 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.178.22 from 192.168.178.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:142.0) Gecko/20100101 Firefox/142.0 Engine version: 3 Transport: polling Total Clients: 4 Sep 16 19:00:34 volumio dbus-daemon[927]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 16 19:00:34 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 16 19:00:34 volumio setdatetime-helper.sh[2375]: Time is not synchronized. Attempting to sync... Sep 16 19:00:34 volumio sudo[2385]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 16 Sep 2025 17:00:34 GMT#015' Sep 16 19:00:34 volumio sudo[2385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 19:00:34 volumio setdatetime-helper.sh[2386]: Tue Sep 16 17:00:34 UTC 2025 Sep 16 19:00:34 volumio systemd-journald[422]: Time jumped backwards, rotating. Sep 16 19:00:34 volumio sudo[2385]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:34 volumio setdatetime-helper.sh[2375]: Time synchronized successfully. Sep 16 19:00:34 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 16 19:00:34 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Sep 16 19:00:34 volumio volumio[1311]: info: Received Get System Info Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 19:00:34 volumio volumio[1311]: info: Discovery: Getting this device information Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:34 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 19:00:34 volumio volumio-remote-updater[954]: Test mode disabled Sep 16 19:00:34 volumio volumio-remote-updater[954]: Alpha mode disabled Sep 16 19:00:34 volumio volumio-remote-updater[954]: Alpha legacy test mode disabled Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:34 volumio volumio[1311]: info: Retrieving Cloud Streaming UI Sep 16 19:00:34 volumio volumio[1311]: info: Getting Tidal Cloud Configuration Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:34 volumio volumio[1311]: info: Getting Qobuz Cloud Configuration Sep 16 19:00:34 volumio volumio[1311]: info: Asking plugin for UI Config Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:34 volumio volumio[1311]: info: Getting Spotify Cloud Configuration Sep 16 19:00:34 volumio volumio[1311]: info: Asking plugin for UI Config Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:34 volumio volumio[1311]: info: Saving Spotify Acccount Sep 16 19:00:34 volumio volumio[1311]: info: Got Tidal Cloud Configuration Sep 16 19:00:34 volumio volumio[1311]: info: Got it Sep 16 19:00:34 volumio volumio[1311]: info: Got it Sep 16 19:00:34 volumio volumio[1311]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:34 volumio volumio[1311]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Sep 16 19:00:34 volumio volumio[1311]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 16 19:00:34 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 16 19:00:35 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 16 19:00:35 volumio volumio[1311]: info: Loading i18n strings for locale pl Sep 16 19:00:35 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 16 19:00:35 volumio volumio[1311]: info: Setting timezone to Europe/Warsaw Sep 16 19:00:35 volumio sudo[2395]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 16 19:00:35 volumio sudo[2395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:35 volumio sudo[2395]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:35 volumio sudo[2399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Warsaw /etc/localtime Sep 16 19:00:35 volumio sudo[2399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:35 volumio sudo[2399]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:35 volumio sudo[2403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 16 19:00:35 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:35 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:35 volumio sudo[2407]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Warsaw Sep 16 19:00:35 volumio sudo[2407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:35 volumio systemd-timedated[2379]: Changed time zone to 'Europe/Warsaw' (CEST). Sep 16 19:00:35 volumio sudo[2407]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:35 volumio volumio[1311]: Updating browse sources language Sep 16 19:00:35 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:00:35 volumio volumio[1311]: info: Fetching Streaming Services browse cache Sep 16 19:00:35 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:00:36 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 16 19:00:36 volumio volumio[1311]: info: MyVolumio login type: Token Sep 16 19:00:36 volumio volumio[1311]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 16 19:00:36 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:00:37 volumio volumio[1311]: info: MyVolumio token set successfully Sep 16 19:00:37 volumio volumio[1311]: info: MYVOLUMIO: Adding device Sep 16 19:00:37 volumio volumio[1311]: info: MYVOLUMIO: Evaluating Server Sep 16 19:00:37 volumio volumio[1311]: info: MyVolumio Plan changed: premium Sep 16 19:00:37 volumio volumio[1311]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 16 19:00:37 volumio volumio[1311]: info: Removing browser output: myVolumio user plan is not superstar Sep 16 19:00:37 volumio volumio[1311]: info: Removing audio output: Sep 16 19:00:37 volumio volumio[1311]: info: MYVOLUMIO: Adding device Sep 16 19:00:37 volumio volumio[1311]: info: MYVOLUMIO: Evaluating Server Sep 16 19:00:37 volumio volumio[1311]: info: Remote config written successfully Sep 16 19:00:37 volumio volumio[1311]: info: Starting Tunnel 1 Sep 16 19:00:37 volumio volumio[1311]: info: Starting Tunnel Connection Checker Sep 16 19:00:37 volumio volumio[1311]: info: MYVolumio Device enabled Sep 16 19:00:37 volumio volumio[1311]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Sep 16 19:00:37 volumio volumio[1311]: info: MyVolumio status changed Sep 16 19:00:37 volumio volumio[1311]: info: Streaming services startup Sep 16 19:00:37 volumio volumio[1311]: info: Starting Streaming Daemon Sep 16 19:00:37 volumio volumio[1311]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Sep 16 19:00:37 volumio sudo[2443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 16 19:00:37 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:37 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:37 volumio volumio[1311]: error: Cannot start Volumio Streaming Daemon Sep 16 19:00:37 volumio volumio[1311]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 16 19:00:37 volumio volumio[1311]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 16 19:00:37 volumio volumio[1311]: info: Loading i18n strings for locale pl Sep 16 19:00:37 volumio volumio[1311]: Updating browse sources language Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 16 19:00:37 volumio volumio[1311]: info: Setting timezone to Europe/Warsaw Sep 16 19:00:37 volumio sudo[2447]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 16 19:00:37 volumio sudo[2447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:37 volumio sudo[2447]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:37 volumio sudo[2451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Warsaw /etc/localtime Sep 16 19:00:37 volumio sudo[2451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:37 volumio sudo[2451]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:37 volumio sudo[2455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 16 19:00:37 volumio sudo[2455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:37 volumio sudo[2455]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:37 volumio sudo[2460]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Warsaw Sep 16 19:00:37 volumio sudo[2460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:37 volumio sudo[2460]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 16 19:00:37 volumio sudo[2463]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 19:00:37 volumio sudo[2463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:37 volumio volumio[1311]: info: Setting Geolocation for MyVolumio to eu6 Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:37 volumio volumio[1311]: info: Setting Geolocation for MyVolumio to eu4 Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:37 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:38 volumio volumio[1311]: info: Successfully Added MyVolumio device Sep 16 19:00:38 volumio volumio[1311]: info: Successfully Added MyVolumio device Sep 16 19:00:38 volumio volumio[1311]: info: Fetching Streaming Services browse cache Sep 16 19:00:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:00:38 volumio volumio[1311]: info: Updating MyVolumio device info Sep 16 19:00:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:38 volumio volumio[1311]: info: Updating MyVolumio device info Sep 16 19:00:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:39 volumio volumio[1311]: info: Successfully Updated MyVolumio device Sep 16 19:00:39 volumio volumio[1311]: info: Successfully Updated MyVolumio device Sep 16 19:00:39 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Sep 16 19:00:39 volumio volumio[1311]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Sep 16 19:00:39 volumio sudo[2467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 16 19:00:39 volumio sudo[2467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:39 volumio sudo[2467]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:39 volumio volumio[1311]: info: MRS: MultiRoom plugin initialized Sep 16 19:00:39 volumio volumio[1311]: info: MRS: STOPPING SNAPCLIENT Sep 16 19:00:39 volumio volumio[1311]: info: MRS: Snap server stop Sep 16 19:00:39 volumio volumio[1311]: info: MRS: STOPPING volumioStreaming Sep 16 19:00:39 volumio sudo[2484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 16 19:00:39 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:39 volumio sudo[2486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 16 19:00:39 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Sep 16 19:00:39 volumio sudo[2488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 16 19:00:39 volumio sudo[2488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:39 volumio sudo[2492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Sep 16 19:00:39 volumio sudo[2492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:39 volumio sudo[2492]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Sep 16 19:00:39 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:39 volumio sudo[2486]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:39 volumio sudo[2488]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Sep 16 19:00:39 volumio volumio[1311]: info: Preparing to generate the ALSA configuration file Sep 16 19:00:39 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Sep 16 19:00:39 volumio volumio[1311]: info: MRS: Removed streaming files Sep 16 19:00:39 volumio volumio[1311]: info: MRS: volumioStreaming STOPPED Sep 16 19:00:39 volumio volumio[1311]: info: MRS: SNAPSERVER STOPPED Sep 16 19:00:39 volumio volumio[1311]: info: MRS: SNAPCLIENT STOPPED Sep 16 19:00:39 volumio volumio[1311]: info: Asound.conf file unchanged, so no further update is needed Sep 16 19:00:39 volumio volumio[1311]: info: Output device has changed, restarting MPD Sep 16 19:00:39 volumio volumio[1311]: info: Output device has changed, restarting Shairport Sync Sep 16 19:00:39 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:39 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:39 volumio sudo[2496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 19:00:39 volumio sudo[2496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:39 volumio sudo[2498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 19:00:39 volumio sudo[2498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:39 volumio sudo[2496]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:39 volumio volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 19:00:39 volumio volumio[1311]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 19:00:39 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:39 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Sep 16 19:00:40 volumio volumio[1311]: ------------------------------------ BT MESSAGE: [FUNC] onStart Sep 16 19:00:40 volumio volumio[1311]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Sep 16 19:00:40 volumio volumio[1311]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Sep 16 19:00:40 volumio volumio[1311]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Sep 16 19:00:40 volumio volumio[1311]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Sep 16 19:00:40 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Sep 16 19:00:40 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Sep 16 19:00:40 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 16 19:00:40 volumio volumio[1311]: info: MRS: Pushing multiroomSync output for this device Sep 16 19:00:40 volumio volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:40 volumio volumio[1311]: info: Adding audio output: Sep 16 19:00:40 volumio volumio[1311]: info: Adding audio output: Sep 16 19:00:40 volumio volumio[1311]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 19:00:40 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:40 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Sep 16 19:00:40 volumio volumio[1311]: info: Adding METAVOLUMIO REST API Endpoints Sep 16 19:00:40 volumio volumio[1311]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 16 19:00:40 volumio volumio[1311]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 16 19:00:40 volumio volumio[1311]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 16 19:00:40 volumio volumio[1311]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 16 19:00:40 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Sep 16 19:00:40 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Sep 16 19:00:40 volumio volumio[1311]: info: Preparing CD Folders Sep 16 19:00:40 volumio volumio[1311]: info: Adding CD REST API Endpoints Sep 16 19:00:40 volumio volumio[1311]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 16 19:00:40 volumio volumio[1311]: info: Starting UDEV Watcher for CD Sep 16 19:00:40 volumio volumio[1311]: info: Detecting CD presence with UDEV Sep 16 19:00:40 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Sep 16 19:00:40 volumio systemd[1]: mpd.service: Deactivated successfully. Sep 16 19:00:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 16 19:00:40 volumio systemd[1]: mpd.socket: Deactivated successfully. Sep 16 19:00:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 16 19:00:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 16 19:00:40 volumio bluetoothd[926]: Path / reserved for Adv Monitor app :1.32 Sep 16 19:00:40 volumio bluetoothd[926]: Adv Monitor app :1.32 disconnected from D-Bus Sep 16 19:00:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 16 19:00:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Sep 16 19:00:40 volumio sudo[2516]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 19:00:40 volumio sudo[2516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 19:00:40 volumio sudo[2516]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:40 volumio mpd[2518]: 2025-09-16T19:00:40 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 19:00:40 volumio systemd[1]: Started mpd.service - Music Player Daemon. Sep 16 19:00:40 volumio sudo[2498]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:41 volumio sudo[2463]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio volumio[1311]: warn: [cd-plugin] cdspeedctl: device or media not ready Sep 16 19:00:45 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Sep 16 19:00:45 volumio volumio[1311]: info: Adding inputs REST Endpoints Sep 16 19:00:45 volumio volumio[1311]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Sep 16 19:00:45 volumio volumio[1311]: info: Scanning Audio Inputs Sep 16 19:00:45 volumio volumio[1311]: info: Checking against Known Cards name Sep 16 19:00:45 volumio volumio[1311]: info: Adding Server instance for streaming Sep 16 19:00:45 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Sep 16 19:00:45 volumio volumio[1311]: error: Hi Res Audio Failed Login: Missing Login Data Sep 16 19:00:45 volumio volumio[1311]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 16 19:00:45 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Sep 16 19:00:45 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Sep 16 19:00:45 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 16 19:00:45 volumio volumio[1311]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Sep 16 19:00:45 volumio volumio[1311]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Sep 16 19:00:45 volumio sudo[2540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:45 volumio sudo[2540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio volumio[1311]: info: QobuzConnect: Starting Qobuz Connect socket and service Sep 16 19:00:45 volumio volumio[1311]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Sep 16 19:00:45 volumio volumio[1311]: info: Stopping AccessToken refresher cron Sep 16 19:00:45 volumio sudo[2547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:00:45 volumio sudo[2547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio volumio[1311]: info: AccessToken refresher cron started Sep 16 19:00:45 volumio volumio[1311]: info: Stopping AccessToken refresher cron for QOBUZ Sep 16 19:00:45 volumio sudo[2540]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio volumio[1311]: info: AccessToken refresher cron started for QOBUZ Sep 16 19:00:45 volumio volumio[1311]: info: Adding TIDAL REST API Endpoints Sep 16 19:00:45 volumio volumio[1311]: info: Adding QOBUZ REST API Endpoints Sep 16 19:00:45 volumio sudo[2547]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio volumio[1311]: info: MRS: Getting audio outputs on start Sep 16 19:00:45 volumio volumio[1311]: info: MRS: Requesting all other devices output Sep 16 19:00:45 volumio sudo[2550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:00:45 volumio sudo[2550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Sep 16 19:00:45 volumio volumio[1311]: info: System name has changed, restarting Shairport Sync Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 19:00:45 volumio volumio[1311]: info: Updating MyVolumio device info Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:45 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Sep 16 19:00:45 volumio volumio[1311]: info: Discovery: Restarting Advertising due to device name change Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Sep 16 19:00:45 volumio sudo[2558]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Sep 16 19:00:45 volumio sudo[2558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio sudo[2558]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 19:00:45 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:00:45 volumio sudo[2550]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio volumio[1311]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Sep 16 19:00:45 volumio volumio[1311]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Sep 16 19:00:45 volumio volumio[1311]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Sep 16 19:00:45 volumio volumio[1311]: info: MPD Permissions set Sep 16 19:00:45 volumio sudo[2568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:00:45 volumio sudo[2568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio sudo[2570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 16 19:00:45 volumio sudo[2570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio volumio[1311]: error: updateQueue error: null Sep 16 19:00:45 volumio sudo[2568]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Sep 16 19:00:45 volumio sudo[2570]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio volumiobt[2582]: INFO [BTSTART] Ensuring Bluetooth directory exists... Sep 16 19:00:45 volumio sudo[2583]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Sep 16 19:00:45 volumio sudo[2583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio sudo[2580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:00:45 volumio sudo[2583]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio sudo[2580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio sudo[2586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Sep 16 19:00:45 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio sudo[2586]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio volumiobt[2588]: INFO [BTSTART] Powering on Bluetooth if needed... Sep 16 19:00:45 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Sep 16 19:00:45 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Sep 16 19:00:45 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:00:45 volumio volumio[1311]: info: Executing endpoint qc_getconfig Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 volumio bluetoothd[926]: Path / reserved for Adv Monitor app :1.33 Sep 16 19:00:45 volumio volumio[1311]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::volumioStop Sep 16 19:00:45 volumio volumio[1311]: info: CoreStateMachine::stop Sep 16 19:00:45 volumio volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 19:00:45 volumio volumio[1311]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Sep 16 19:00:45 volumio volumio[1311]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 19:00:45 volumio bluetoothd[926]: Adv Monitor app :1.33 disconnected from D-Bus Sep 16 19:00:45 volumio volumiobt[2599]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Sep 16 19:00:45 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:00:45 volumio sudo[2598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Sep 16 19:00:45 volumio sudo[2598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio sudo[2580]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio volumio[1311]: info: Starting Shairport Sync Sep 16 19:00:45 volumio sudo[2603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Sep 16 19:00:45 volumio sudo[2603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio volumio[1311]: info: Starting Shairport Sync Sep 16 19:00:45 volumio volumiobt[2601]: [176B blob data] Sep 16 19:00:45 volumio volumiobt[2601]: [157B blob data] Sep 16 19:00:45 volumio volumiobt[2601]: [157B blob data] Sep 16 19:00:45 volumio volumiobt[2601]: [157B blob data] Sep 16 19:00:45 volumio volumiobt[2601]: [113B blob data] Sep 16 19:00:45 volumio volumiobt[2601]: [bluetoothctl]> discoverable on Sep 16 19:00:45 volumio volumiobt[2601]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Sep 16 19:00:45 volumio volumiobt[2601]: [bluetoothctl]> pairable on Sep 16 19:00:45 volumio volumiobt[2601]: [170B blob data] Sep 16 19:00:45 volumio sudo[2606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 19:00:45 volumio sudo[2606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio bluetoothd[926]: Path / reserved for Adv Monitor app :1.34 Sep 16 19:00:45 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. Sep 16 19:00:45 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. Sep 16 19:00:45 volumio sudo[2598]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio volumio[1311]: info: Preparing to generate the ALSA configuration file Sep 16 19:00:45 volumio bluetoothd[926]: Adv Monitor app :1.34 disconnected from D-Bus Sep 16 19:00:45 volumio sudo[2609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 19:00:45 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Sep 16 19:00:45 volumio sudo[2609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 volumio volumiobt[2601]: [bluetoothctl]> Sep 16 19:00:45 volumio volumiobt[2612]: INFO [BTSTART] Registering Bluetooth agent... Sep 16 19:00:45 volumio sudo[2603]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio autossh[2613]: port set to 0, monitoring disabled Sep 16 19:00:45 volumio volumio[1311]: info: Remote SSH Started Sep 16 19:00:45 volumio autossh[2613]: starting ssh (count 1) Sep 16 19:00:45 volumio autossh[2613]: ssh child pid is 2618 Sep 16 19:00:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 16 19:00:45 volumio volumiobt[2615]: [NEW] Media /org/bluez/hci0 Sep 16 19:00:45 volumio volumiobt[2615]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Sep 16 19:00:45 volumio volumiobt[2615]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Sep 16 19:00:45 volumio volumiobt[2615]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Sep 16 19:00:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Sep 16 19:00:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:45 volumio systemd[1]: shairport-sync.service: Consumed 1.540s CPU time. Sep 16 19:00:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:45 volumio sudo[2609]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio sudo[2606]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 volumio bluetoothd[926]: Adv Monitor app :1.35 disconnected from D-Bus Sep 16 19:00:45 volumio volumiobt[2620]: No agent is registered Sep 16 19:00:45 volumio volumiobt[2620]: [NEW] Media /org/bluez/hci0 Sep 16 19:00:45 volumio volumiobt[2620]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Sep 16 19:00:45 volumio volumiobt[2620]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Sep 16 19:00:45 volumio volumiobt[2620]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Sep 16 19:00:45 volumio bluetoothd[926]: Adv Monitor app :1.36 disconnected from D-Bus Sep 16 19:00:45 volumio volumiobt[2635]: INFO [BTSTART] Agent registered successfully. Sep 16 19:00:45 volumio volumiobt[2636]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Sep 16 19:00:45 volumio volumio[1311]: info: Permissions for /etc/hosts set Sep 16 19:00:45 volumio volumio[1311]: info: Executing endpoint qc_getconfig Sep 16 19:00:45 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 16 19:00:45 volumio qobuz-connect[2591]: 20250916 19:00:45.347 [2591.2591] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 16 19:00:45 volumio volumio[1311]: info: Shairport-Sync Started Sep 16 19:00:45 volumio volumio[1311]: info: Shairport-Sync Started Sep 16 19:00:45 volumio volumio[1311]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 16 19:00:45 volumio volumio[1311]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 16 19:00:45 volumio volumio[1311]: info: Asound.conf file unchanged, so no further update is needed Sep 16 19:00:45 volumio volumio[1311]: info: Output device has changed, restarting MPD Sep 16 19:00:45 volumio sudo[2639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname rotel Sep 16 19:00:45 volumio sudo[2639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel sudo[2639]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel volumio[1311]: info: Output device has changed, restarting Shairport Sync Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 rotel sudo[2645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 19:00:45 rotel sudo[2645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel sudo[2642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 19:00:45 rotel sudo[2642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel sudo[2642]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 19:00:45 rotel volumio[1311]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 19:00:45 rotel systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:45 rotel volumio[1311]: info: Preparing to generate the ALSA configuration file Sep 16 19:00:45 rotel systemd[1]: mpd.service: Deactivated successfully. Sep 16 19:00:45 rotel systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 16 19:00:45 rotel systemd[1]: mpd.socket: Deactivated successfully. Sep 16 19:00:45 rotel systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 16 19:00:45 rotel systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 16 19:00:45 rotel volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 16 19:00:45 rotel volumio[1311]: info: Hostname now is rotel Sep 16 19:00:45 rotel sudo[2659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:00:45 rotel sudo[2659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel volumio[1311]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 16 19:00:45 rotel volumio[1311]: info: Reading ALSA contributions from plugins. Sep 16 19:00:45 rotel systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 16 19:00:45 rotel systemd[1]: Starting mpd.service - Music Player Daemon... Sep 16 19:00:45 rotel volumiossh-tunnel[2618]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Sep 16 19:00:45 rotel sudo[2661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Sep 16 19:00:45 rotel volumio[1311]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Sep 16 19:00:45 rotel volumio[1311]: info: MPD Permissions set Sep 16 19:00:45 rotel volumio[1311]: info: New hostname set Sep 16 19:00:45 rotel sudo[2661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel qobuz-connect[2591]: 20250916 19:00:45.443 [2591.2591] INFO VolumeManager: [0x6cd368]: Setting new playback volume: 75 Sep 16 19:00:45 rotel qobuz-connect[2591]: 20250916 19:00:45.443 [2591.2591] INFO VolumeManager: [0x6cd368]: Setting new mute state: 0 Sep 16 19:00:45 rotel qobuz-connect[2591]: 20250916 19:00:45.443 [2591.2591] INFO QobuzConnect: [0x6cdd38]: Client initialized! Sep 16 19:00:45 rotel qobuz-connect[2591]: 20250916 19:00:45.443 [2591.2591] INFO SampleApp: Starting Avahi advertising, name: Rotel, service name: _qobuz-connect._tcp Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel qobuz-connect[2591]: 20250916 19:00:45.457 [2591.2591] INFO LocalConfigManager: [0x6ccc48]: Starting Local Configuration server Sep 16 19:00:45 rotel qobuz-connect[2591]: 20250916 19:00:45.457 [2591.2591] INFO SampleApp: Starting Local configuration server Sep 16 19:00:45 rotel qobuz-connect[2591]: 20250916 19:00:45.457 [2591.2591] INFO SampleApp: Connected to UNIX socket client 0x6b7818 Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel sudo[2659]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel sudo[2661]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel sudo[2672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:00:45 rotel sudo[2672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:45 rotel qobuz-connect[2591]: 20250916 19:00:45.494 [2591.2591] INFO SampleApp: Stopping Local configuration server Sep 16 19:00:45 rotel sudo[2663]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 19:00:45 rotel sudo[2663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 19:00:45 rotel volumio[1311]: info: Permissions for /etc/avahi/services/volumio.service Sep 16 19:00:45 rotel sudo[2663]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:45 rotel volumio[1311]: info: Starting Shairport Sync Sep 16 19:00:45 rotel volumio[1311]: info: Avahi name changed to rotel Sep 16 19:00:45 rotel avahi-daemon[920]: Files changed, reloading. Sep 16 19:00:45 rotel avahi-daemon[920]: Loading service file /services/volumio.service. Sep 16 19:00:45 rotel volumio[1311]: info: Asound.conf file written Sep 16 19:00:45 rotel sudo[2677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 19:00:45 rotel sudo[2680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 16 19:00:45 rotel sudo[2680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel sudo[2677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel sudo[2680]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel systemd[1]: upmpdcli.service: Deactivated successfully. Sep 16 19:00:45 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Sep 16 19:00:45 rotel volumio[1311]: No state is present for card vc4hdmi0 Sep 16 19:00:45 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Sep 16 19:00:45 rotel volumio[1311]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 16 19:00:45 rotel volumio[1311]: Hardware is initialized using a generic method Sep 16 19:00:45 rotel volumio[1311]: No state is present for card vc4hdmi0 Sep 16 19:00:45 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Sep 16 19:00:45 rotel volumio[1311]: No state is present for card vc4hdmi1 Sep 16 19:00:45 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Sep 16 19:00:45 rotel volumio[1311]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 16 19:00:45 rotel volumio[1311]: Hardware is initialized using a generic method Sep 16 19:00:45 rotel volumio[1311]: No state is present for card vc4hdmi1 Sep 16 19:00:45 rotel volumio[1311]: No state is present for card Tone2 Sep 16 19:00:45 rotel volumio[1311]: Found hardware: "USB-Audio" "USB Mixer" "USB3353:a004" "" "" Sep 16 19:00:45 rotel volumio[1311]: Hardware is initialized using a generic method Sep 16 19:00:45 rotel volumio[1311]: No state is present for card Tone2 Sep 16 19:00:45 rotel volumio[1311]: info: Output device has changed, restarting MPD Sep 16 19:00:45 rotel systemd[1]: upmpdcli.service: Consumed 10.827s CPU time. Sep 16 19:00:45 rotel volumio[1311]: info: Output device has changed, restarting Shairport Sync Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 rotel systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 16 19:00:45 rotel systemd[1]: shairport-sync.service: Deactivated successfully. Sep 16 19:00:45 rotel systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:45 rotel systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:45 rotel sudo[2677]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel sudo[2689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 19:00:45 rotel sudo[2689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel sudo[2687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 19:00:45 rotel sudo[2687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel sudo[2687]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 19:00:45 rotel volumio[1311]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:45 rotel systemd[1]: mpd.service: Deactivated successfully. Sep 16 19:00:45 rotel systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 16 19:00:45 rotel systemd[1]: mpd.socket: Deactivated successfully. Sep 16 19:00:45 rotel systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 16 19:00:45 rotel systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 16 19:00:45 rotel volumio[1311]: info: Successfully Updated MyVolumio device Sep 16 19:00:45 rotel volumio[1311]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Sep 16 19:00:45 rotel volumio[1311]: info: MPD Permissions set Sep 16 19:00:45 rotel volumio[1311]: info: Shairport-Sync Started Sep 16 19:00:45 rotel systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 16 19:00:45 rotel sudo[2718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:00:45 rotel sudo[2718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel systemd[1]: Starting mpd.service - Music Player Daemon... Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel sudo[2718]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:45 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:45 rotel sudo[2727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:00:45 rotel sudo[2727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel volumio[1311]: info: Starting Shairport Sync Sep 16 19:00:45 rotel sudo[2730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 19:00:45 rotel sudo[2730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:45 rotel sudo[2721]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 19:00:45 rotel sudo[2721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 19:00:45 rotel sudo[2721]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 16 19:00:45 rotel systemd[1]: shairport-sync.service: Deactivated successfully. Sep 16 19:00:45 rotel systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:45 rotel systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:45 rotel sudo[2730]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:45 rotel volumio[1311]: info: Shairport-Sync Started Sep 16 19:00:45 rotel volumiobt[2637]: INFO:a2dp-agent:Connecting to system bus. Sep 16 19:00:45 rotel volumiobt[2637]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Sep 16 19:00:45 rotel volumiobt[2637]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Sep 16 19:00:45 rotel volumiobt[2637]: INFO:a2dp-agent:Registering agent. Sep 16 19:00:45 rotel volumiobt[2637]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Sep 16 19:00:45 rotel volumiobt[2637]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Sep 16 19:00:46 rotel volumio[1311]: info: Discovery: A device disappeared from network Sep 16 19:00:46 rotel volumio[1311]: info: Discovery: Device volumio disappeared from network Sep 16 19:00:46 rotel volumio[1311]: info: Discovery: A device disappeared from network Sep 16 19:00:46 rotel volumio[1311]: info: Discovery: A device disappeared from network Sep 16 19:00:46 rotel mpd[2733]: 2025-09-16T19:00:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 19:00:46 rotel systemd[1]: Started mpd.service - Music Player Daemon. Sep 16 19:00:46 rotel sudo[2689]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:46 rotel sudo[2645]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:46 rotel volumio[1311]: error: updateQueue error: null Sep 16 19:00:46 rotel volumio[1311]: error: updateQueue error: null Sep 16 19:00:46 rotel avahi-daemon[920]: Service "Rotel" (/services/volumio.service) successfully established. Sep 16 19:00:47 rotel qobuz-connect[2591]: 20250916 19:00:47.461 [2591.2591] INFO SampleApp: shat down connection on UNIX socket Sep 16 19:00:47 rotel volumio[1311]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 16 19:00:47 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:47 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:47 rotel systemd[1]: qobuz-connect.service: Deactivated successfully. Sep 16 19:00:47 rotel systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:00:47 rotel sudo[2672]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:47 rotel systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:00:47 rotel sudo[2727]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:47 rotel volumio[1311]: info: Executing endpoint qc_getconfig Sep 16 19:00:47 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 16 19:00:47 rotel qobuz-connect[2754]: 20250916 19:00:47.494 [2754.2754] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 16 19:00:47 rotel volumio[1311]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 16 19:00:47 rotel volumio[1311]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 16 19:00:47 rotel qobuz-connect[2754]: 20250916 19:00:47.496 [2754.2754] INFO VolumeManager: [0x19cf368]: Setting new playback volume: 75 Sep 16 19:00:47 rotel qobuz-connect[2754]: 20250916 19:00:47.496 [2754.2754] INFO VolumeManager: [0x19cf368]: Setting new mute state: 0 Sep 16 19:00:47 rotel qobuz-connect[2754]: 20250916 19:00:47.496 [2754.2754] INFO QobuzConnect: [0x19cfd38]: Client initialized! Sep 16 19:00:47 rotel qobuz-connect[2754]: 20250916 19:00:47.496 [2754.2754] INFO SampleApp: Starting Avahi advertising, name: Rotel, service name: _qobuz-connect._tcp Sep 16 19:00:47 rotel qobuz-connect[2754]: 20250916 19:00:47.499 [2754.2754] INFO LocalConfigManager: [0x19cec48]: Starting Local Configuration server Sep 16 19:00:47 rotel qobuz-connect[2754]: 20250916 19:00:47.499 [2754.2754] INFO SampleApp: Starting Local configuration server Sep 16 19:00:47 rotel qobuz-connect[2754]: 20250916 19:00:47.500 [2754.2754] INFO SampleApp: Connected to UNIX socket client 0x19b9818 Sep 16 19:00:47 rotel qobuz-connect[2754]: 20250916 19:00:47.645 [2754.2754] INFO SampleApp: Playback volume changed: 75 Sep 16 19:00:47 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:48 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:48 rotel volumio[1311]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:48 rotel volumio[1311]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:48 rotel sudo[2766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 16 19:00:48 rotel sudo[2766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:48 rotel systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 16 19:00:48 rotel sudo[2766]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:48 rotel volumio[1311]: info: Executing endpoint tc_getconfig Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 16 19:00:48 rotel vtcs[2769]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 16 19:00:48 rotel vtcs[2769]: STARTED TidalConnect services. Sep 16 19:00:48 rotel volumio[1311]: info: Executing endpoint tc_connect Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 16 19:00:48 rotel volumio[1311]: info: Connecting to TidalConnect Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::servicePushState Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:48 rotel volumio[1311]: info: CorePlayQueue::getTrack 0 Sep 16 19:00:48 rotel volumio[1311]: 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} Sep 16 19:00:48 rotel volumio[1311]: verbose: CURRENT POSITION 0 Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::syncState stateService stop Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::syncState currentStatus stop Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:48 rotel volumio[1311]: info: No code Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::servicePushState Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:48 rotel volumio[1311]: info: CorePlayQueue::getTrack 0 Sep 16 19:00:48 rotel volumio[1311]: 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} Sep 16 19:00:48 rotel volumio[1311]: verbose: CURRENT POSITION 0 Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::syncState stateService stop Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::syncState currentStatus stop Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:48 rotel volumio[1311]: info: No code Sep 16 19:00:48 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:48 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:48 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:48 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:48 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:48 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:48 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 16 19:00:49 rotel volumio[1311]: info: Preparing to save Alsa Options, stopping services first Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:49 rotel volumio[1311]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"Tone2"},"disallowPush":true} Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 19:00:49 rotel volumio[1311]: info: Setting mixer Tone2 for card Tone2 Sep 16 19:00:49 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:49 rotel vtcs[2769]: [2025-09-16 19:00:49.120] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 16 19:00:49 rotel vtcs[2769]: [2025-09-16 19:00:49.120] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 16 19:00:49 rotel sudo[2797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel sudo[2797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 16 19:00:49 rotel volumio[1311]: info: Updating Volume Controller Parameters: Device: 5 Name: Tone2 Mixer: Tone2 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 16 19:00:49 rotel volumio[1311]: info: Disabling external Volume Control Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:49 rotel volumio[1311]: info: Preparing to generate the ALSA configuration file Sep 16 19:00:49 rotel systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Sep 16 19:00:49 rotel systemd[1]: vtcs.service: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 16 19:00:49 rotel sudo[2804]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel sudo[2797]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[2804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 16 19:00:49 rotel sudo[2812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:00:49 rotel sudo[2812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 16 19:00:49 rotel sudo[2804]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 16 19:00:49 rotel sudo[2815]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 19:00:49 rotel sudo[2815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 16 19:00:49 rotel sudo[2812]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 16 19:00:49 rotel volumio[1311]: info: Reading ALSA contributions from plugins. Sep 16 19:00:49 rotel sudo[2820]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 19:00:49 rotel sudo[2818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:00:49 rotel sudo[2818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2820]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: VolumeController:: Volume=100 Mute =false Sep 16 19:00:49 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:49 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:49 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:49 rotel volumio[1311]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 16 19:00:49 rotel volumio[1311]: wlan0 Interface doesn't support scanning : Device or resource busy Sep 16 19:00:49 rotel volumio[1311]: info: Cannot use regular scanning, forcing with ap-force Sep 16 19:00:49 rotel systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Sep 16 19:00:49 rotel qobuz-connect[2754]: 20250916 19:00:49.198 [2754.2754] INFO SampleApp: Stopping Local configuration server Sep 16 19:00:49 rotel sudo[2826]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 16 19:00:49 rotel sudo[2826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2826]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: command failed: Device or resource busy (-16) Sep 16 19:00:49 rotel volumio[1311]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 16 19:00:49 rotel volumio[1311]: command failed: Device or resource busy (-16) Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 16 19:00:49 rotel volumio[1311]: info: Asound.conf file written Sep 16 19:00:49 rotel sudo[2837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 16 19:00:49 rotel sudo[2837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2837]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Sep 16 19:00:49 rotel volumio[1311]: No state is present for card vc4hdmi0 Sep 16 19:00:49 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Sep 16 19:00:49 rotel volumio[1311]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 16 19:00:49 rotel volumio[1311]: Hardware is initialized using a generic method Sep 16 19:00:49 rotel volumio[1311]: No state is present for card vc4hdmi0 Sep 16 19:00:49 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Sep 16 19:00:49 rotel volumio[1311]: No state is present for card vc4hdmi1 Sep 16 19:00:49 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Sep 16 19:00:49 rotel volumio[1311]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 16 19:00:49 rotel volumio[1311]: Hardware is initialized using a generic method Sep 16 19:00:49 rotel volumio[1311]: No state is present for card vc4hdmi1 Sep 16 19:00:49 rotel volumio[1311]: No state is present for card Tone2 Sep 16 19:00:49 rotel volumio[1311]: Found hardware: "USB-Audio" "USB Mixer" "USB3353:a004" "" "" Sep 16 19:00:49 rotel volumio[1311]: Hardware is initialized using a generic method Sep 16 19:00:49 rotel volumio[1311]: No state is present for card Tone2 Sep 16 19:00:49 rotel volumio[1311]: info: Output device has changed, restarting MPD Sep 16 19:00:49 rotel volumio[1311]: info: Output device has changed, restarting Shairport Sync Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:49 rotel sudo[2845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 19:00:49 rotel sudo[2843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 19:00:49 rotel sudo[2845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2843]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 19:00:49 rotel volumio[1311]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:49 rotel systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 16 19:00:49 rotel volumio[1311]: info: Output device has changed, restarting MPD Sep 16 19:00:49 rotel sudo[2855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel sudo[2855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: Output device has changed, restarting Shairport Sync Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:49 rotel systemd[1]: mpd.service: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 16 19:00:49 rotel systemd[1]: mpd.socket: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 16 19:00:49 rotel systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 16 19:00:49 rotel sudo[2857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 19:00:49 rotel sudo[2857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2857]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 19:00:49 rotel volumio[1311]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:49 rotel sudo[2861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 19:00:49 rotel sudo[2861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 16 19:00:49 rotel sudo[2871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel sudo[2871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: Starting mpd.service - Music Player Daemon... Sep 16 19:00:49 rotel sudo[2873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel sudo[2873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel sudo[2878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:00:49 rotel sudo[2887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: mpd.service: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 16 19:00:49 rotel volumio[1311]: info: MPD Permissions set Sep 16 19:00:49 rotel volumio[1311]: info: MPD Permissions set Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel sudo[2855]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[2892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:00:49 rotel sudo[2892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel systemd[1]: mpd.socket: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 16 19:00:49 rotel systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 16 19:00:49 rotel sudo[2871]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[2887]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[2873]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel sudo[2905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:00:49 rotel sudo[2905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: Starting Shairport Sync Sep 16 19:00:49 rotel volumio[1311]: info: Starting Shairport Sync Sep 16 19:00:49 rotel systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 16 19:00:49 rotel systemd[1]: Starting mpd.service - Music Player Daemon... Sep 16 19:00:49 rotel sudo[2911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 19:00:49 rotel sudo[2911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 19:00:49 rotel sudo[2913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2878]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: Preparing to generate the ALSA configuration file Sep 16 19:00:49 rotel volumio[1311]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 16 19:00:49 rotel volumio[1311]: info: Reading ALSA contributions from plugins. Sep 16 19:00:49 rotel sudo[2892]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[2918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:00:49 rotel sudo[2918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: Asound.conf file written Sep 16 19:00:49 rotel systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 16 19:00:49 rotel sudo[2914]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 19:00:49 rotel sudo[2914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 19:00:49 rotel sudo[2922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 16 19:00:49 rotel sudo[2922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: shairport-sync.service: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:49 rotel sudo[2914]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel systemd[1]: shairport-sync.service: Consumed 1.493s CPU time. Sep 16 19:00:49 rotel sudo[2922]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Sep 16 19:00:49 rotel volumio[1311]: No state is present for card vc4hdmi0 Sep 16 19:00:49 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Sep 16 19:00:49 rotel volumio[1311]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 16 19:00:49 rotel volumio[1311]: Hardware is initialized using a generic method Sep 16 19:00:49 rotel volumio[1311]: No state is present for card vc4hdmi0 Sep 16 19:00:49 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Sep 16 19:00:49 rotel volumio[1311]: No state is present for card vc4hdmi1 Sep 16 19:00:49 rotel volumio[1311]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Sep 16 19:00:49 rotel volumio[1311]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 16 19:00:49 rotel volumio[1311]: Hardware is initialized using a generic method Sep 16 19:00:49 rotel volumio[1311]: No state is present for card vc4hdmi1 Sep 16 19:00:49 rotel volumio[1311]: No state is present for card Tone2 Sep 16 19:00:49 rotel volumio[1311]: Found hardware: "USB-Audio" "USB Mixer" "USB3353:a004" "" "" Sep 16 19:00:49 rotel volumio[1311]: Hardware is initialized using a generic method Sep 16 19:00:49 rotel volumio[1311]: No state is present for card Tone2 Sep 16 19:00:49 rotel volumio[1311]: info: Output device has changed, restarting MPD Sep 16 19:00:49 rotel volumio[1311]: info: Output device has changed, restarting Shairport Sync Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:49 rotel sudo[2933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 19:00:49 rotel sudo[2933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 19:00:49 rotel sudo[2931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2931]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 19:00:49 rotel volumio[1311]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:49 rotel sudo[2913]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:49 rotel sudo[2911]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: Preparing to generate the ALSA configuration file Sep 16 19:00:49 rotel systemd[1]: mpd.service: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 16 19:00:49 rotel sudo[2944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel sudo[2944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: mpd.socket: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 16 19:00:49 rotel systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 16 19:00:49 rotel systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 16 19:00:49 rotel qobuz-connect[2754]: 20250916 19:00:49.504 [2754.2754] INFO SampleApp: shat down connection on UNIX socket Sep 16 19:00:49 rotel systemd[1]: Starting mpd.service - Music Player Daemon... Sep 16 19:00:49 rotel sudo[2963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel systemd[1]: qobuz-connect.service: Deactivated successfully. Sep 16 19:00:49 rotel sudo[2963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:00:49 rotel systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:00:49 rotel sudo[2918]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[2905]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[2818]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 16 19:00:49 rotel volumio[1311]: info: Reading ALSA contributions from plugins. Sep 16 19:00:49 rotel volumio[1311]: info: MPD Permissions set Sep 16 19:00:49 rotel volumio[1311]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 16 19:00:49 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:49 rotel volumio[1311]: info: Shairport-Sync Started Sep 16 19:00:49 rotel volumio[1311]: info: Shairport-Sync Started Sep 16 19:00:49 rotel volumio[1311]: info: Executing endpoint qc_getconfig Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel sudo[2971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.552 [2969.2969] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.553 [2969.2969] INFO VolumeManager: [0x122b368]: Setting new playback volume: 75 Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.553 [2969.2969] INFO VolumeManager: [0x122b368]: Setting new mute state: 0 Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.553 [2969.2969] INFO QobuzConnect: [0x122bd38]: Client initialized! Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.553 [2969.2969] INFO SampleApp: Starting Avahi advertising, name: Rotel, service name: _qobuz-connect._tcp Sep 16 19:00:49 rotel sudo[2971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2944]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.559 [2969.2969] INFO LocalConfigManager: [0x122ac48]: Starting Local Configuration server Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.559 [2969.2969] INFO SampleApp: Starting Local configuration server Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.559 [2969.2969] INFO SampleApp: Connected to UNIX socket client 0x1215818 Sep 16 19:00:49 rotel sudo[2961]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 19:00:49 rotel sudo[2961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:49 rotel sudo[2961]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 16 19:00:49 rotel volumio[1311]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 16 19:00:49 rotel sudo[2963]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: Starting Shairport Sync Sep 16 19:00:49 rotel volumio[1311]: info: Asound.conf file unchanged, so no further update is needed Sep 16 19:00:49 rotel volumio[1311]: info: Output device has changed, restarting MPD Sep 16 19:00:49 rotel volumio[1311]: info: Output device has changed, restarting Shairport Sync Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:49 rotel sudo[2987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 19:00:49 rotel sudo[2987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 19:00:49 rotel sudo[2985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[2987]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[2989]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 19:00:49 rotel sudo[2989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 19:00:49 rotel volumio[1311]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:49 rotel sudo[3002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel sudo[3002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel sudo[3001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:00:49 rotel sudo[3001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel volumio[1311]: info: MPD Permissions set Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel sudo[2971]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[3010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:00:49 rotel sudo[3010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:49 rotel systemd[1]: shairport-sync.service: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:49 rotel systemd[1]: mpd.service: Deactivated successfully. Sep 16 19:00:49 rotel sudo[3016]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:00:49 rotel sudo[3016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 16 19:00:49 rotel volumio[1311]: info: Starting Shairport Sync Sep 16 19:00:49 rotel sudo[3020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 19:00:49 rotel sudo[3020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: mpd.socket: Deactivated successfully. Sep 16 19:00:49 rotel sudo[3010]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 16 19:00:49 rotel systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 16 19:00:49 rotel systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 16 19:00:49 rotel systemd[1]: Starting mpd.service - Music Player Daemon... Sep 16 19:00:49 rotel sudo[3022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:00:49 rotel sudo[3022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:49 rotel systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:49 rotel sudo[2985]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: Shairport-Sync Started Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.701 [2969.2969] INFO SampleApp: Playback volume changed: 75 Sep 16 19:00:49 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:49 rotel sudo[3002]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[3024]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 19:00:49 rotel sudo[3024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 19:00:49 rotel sudo[3001]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel sudo[3024]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel qobuz-connect[2969]: 20250916 19:00:49.780 [2969.2969] INFO SampleApp: Stopping Local configuration server Sep 16 19:00:49 rotel systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Sep 16 19:00:49 rotel systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 16 19:00:49 rotel systemd[1]: shairport-sync.service: Deactivated successfully. Sep 16 19:00:49 rotel systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:49 rotel systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 16 19:00:49 rotel systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 16 19:00:49 rotel systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:00:49 rotel sudo[3020]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:49 rotel volumio[1311]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 16 19:00:49 rotel volumio[1311]: Job for shairport-sync.service failed because start of the service was attempted too often. Sep 16 19:00:49 rotel volumio[1311]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Sep 16 19:00:49 rotel volumio[1311]: To force a start use "systemctl reset-failed shairport-sync.service" Sep 16 19:00:49 rotel volumio[1311]: followed by "systemctl start shairport-sync.service" again. Sep 16 19:00:50 rotel volumio[1311]: info: Discovery: Started advertising with name: Rotel Sep 16 19:00:50 rotel mpd[3044]: 2025-09-16T19:00:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 19:00:50 rotel systemd[1]: Started mpd.service - Music Player Daemon. Sep 16 19:00:50 rotel sudo[2845]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:50 rotel sudo[2861]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:50 rotel sudo[2933]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:50 rotel sudo[2989]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:50 rotel volumio[1311]: error: MPD error: The expression evaluated to a falsy value: Sep 16 19:00:50 rotel volumio[1311]: assert.ok(self.idling) Sep 16 19:00:50 rotel volumio[1311]: error: The expression evaluated to a falsy value: Sep 16 19:00:50 rotel volumio[1311]: assert.ok(self.idling) Sep 16 19:00:50 rotel volumio[1311]: error: MPD error: The expression evaluated to a falsy value: Sep 16 19:00:50 rotel volumio[1311]: assert.ok(self.idling) Sep 16 19:00:50 rotel volumio[1311]: error: The expression evaluated to a falsy value: Sep 16 19:00:50 rotel volumio[1311]: assert.ok(self.idling) Sep 16 19:00:50 rotel volumio[1311]: error: MPD error: The expression evaluated to a falsy value: Sep 16 19:00:50 rotel volumio[1311]: assert.ok(self.idling) Sep 16 19:00:50 rotel volumio[1311]: error: The expression evaluated to a falsy value: Sep 16 19:00:50 rotel volumio[1311]: assert.ok(self.idling) Sep 16 19:00:50 rotel volumio[1311]: error: updateQueue error: null Sep 16 19:00:50 rotel volumio[1311]: info: Discovery: adding 95acf0fe-f5da-4178-944d-ebc450225f1c Sep 16 19:00:50 rotel volumio[1311]: info: Discovery: Found device Rotel Sep 16 19:00:50 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:51 rotel volumio[1311]: info: Discovery: this is already registered, 95acf0fe-f5da-4178-944d-ebc450225f1c Sep 16 19:00:51 rotel volumio[1311]: info: Discovery: Found device Rotel Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:51 rotel volumio[1311]: info: Discovery: this is already registered, 95acf0fe-f5da-4178-944d-ebc450225f1c Sep 16 19:00:51 rotel volumio[1311]: info: Discovery: Found device Rotel Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:51 rotel volumio[1311]: info: Retrieving Cloud Streaming UI Sep 16 19:00:51 rotel volumio[1311]: info: Getting Tidal Cloud Configuration Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:51 rotel volumio[1311]: info: Getting Qobuz Cloud Configuration Sep 16 19:00:51 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:51 rotel volumio[1311]: info: Getting Spotify Cloud Configuration Sep 16 19:00:51 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:51 rotel volumio[1311]: info: Saving Spotify Acccount Sep 16 19:00:51 rotel volumio[1311]: info: Got it Sep 16 19:00:51 rotel volumio[1311]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 16 19:00:51 rotel volumio[1311]: info: Got Tidal Cloud Configuration Sep 16 19:00:51 rotel volumio[1311]: info: Got it Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:51 rotel volumio[1311]: info: TidalConnect service started! Sep 16 19:00:51 rotel qobuz-connect[2969]: 20250916 19:00:51.572 [2969.2969] INFO SampleApp: shat down connection on UNIX socket Sep 16 19:00:51 rotel volumio[1311]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 16 19:00:51 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:51 rotel systemd[1]: qobuz-connect.service: Deactivated successfully. Sep 16 19:00:51 rotel systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:00:51 rotel systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:00:51 rotel sudo[3016]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:51 rotel sudo[3022]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:51 rotel volumio[1311]: info: Executing endpoint qc_getconfig Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 16 19:00:51 rotel qobuz-connect[3065]: 20250916 19:00:51.608 [3065.3065] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 16 19:00:51 rotel qobuz-connect[3065]: 20250916 19:00:51.610 [3065.3065] INFO VolumeManager: [0xe11368]: Setting new playback volume: 75 Sep 16 19:00:51 rotel qobuz-connect[3065]: 20250916 19:00:51.610 [3065.3065] INFO VolumeManager: [0xe11368]: Setting new mute state: 0 Sep 16 19:00:51 rotel qobuz-connect[3065]: 20250916 19:00:51.610 [3065.3065] INFO QobuzConnect: [0xe11d38]: Client initialized! Sep 16 19:00:51 rotel qobuz-connect[3065]: 20250916 19:00:51.610 [3065.3065] INFO SampleApp: Starting Avahi advertising, name: Rotel, service name: _qobuz-connect._tcp Sep 16 19:00:51 rotel volumio[1311]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 16 19:00:51 rotel volumio[1311]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 16 19:00:51 rotel qobuz-connect[3065]: 20250916 19:00:51.613 [3065.3065] INFO LocalConfigManager: [0xe10c48]: Starting Local Configuration server Sep 16 19:00:51 rotel qobuz-connect[3065]: 20250916 19:00:51.613 [3065.3065] INFO SampleApp: Starting Local configuration server Sep 16 19:00:51 rotel qobuz-connect[3065]: 20250916 19:00:51.614 [3065.3065] INFO SampleApp: Connected to UNIX socket client 0xdfb818 Sep 16 19:00:51 rotel qobuz-connect[3065]: 20250916 19:00:51.759 [3065.3065] INFO SampleApp: Playback volume changed: 75 Sep 16 19:00:51 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 16 19:00:52 rotel volumio[1311]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 16 19:00:52 rotel volumio[1311]: info: Received Get System Version Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 19:00:52 rotel volumio[1311]: info: Received Get System Info Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 19:00:52 rotel volumio[1311]: info: Discovery: Getting this device information Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel sudo[3077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 16 19:00:52 rotel sudo[3077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:52 rotel systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 16 19:00:52 rotel sudo[3077]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:52 rotel volumio[1311]: info: Executing endpoint tc_getconfig Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 16 19:00:52 rotel vtcs[3080]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 16 19:00:52 rotel vtcs[3080]: STARTED TidalConnect services. Sep 16 19:00:52 rotel volumio[1311]: info: Executing endpoint tc_connect Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 16 19:00:52 rotel volumio[1311]: info: Connecting to TidalConnect Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::servicePushState Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:52 rotel volumio[1311]: info: CorePlayQueue::getTrack 0 Sep 16 19:00:52 rotel volumio[1311]: 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} Sep 16 19:00:52 rotel volumio[1311]: verbose: CURRENT POSITION 0 Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::syncState stateService stop Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::syncState currentStatus stop Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:52 rotel volumio[1311]: info: No code Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::servicePushState Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:52 rotel volumio[1311]: info: CorePlayQueue::getTrack 0 Sep 16 19:00:52 rotel volumio[1311]: 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} Sep 16 19:00:52 rotel volumio[1311]: verbose: CURRENT POSITION 0 Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::syncState stateService stop Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::syncState currentStatus stop Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:52 rotel volumio[1311]: info: No code Sep 16 19:00:52 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:52 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:52 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:52 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:52 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:52 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:52 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:52 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:52 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel sudo[3107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 16 19:00:52 rotel sudo[3107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:52 rotel sudo[3107]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel sudo[3119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 16 19:00:52 rotel sudo[3119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:52 rotel sudo[3119]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel sudo[3131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 16 19:00:52 rotel sudo[3131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:52 rotel sudo[3131]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel volumio[1311]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:00:52 rotel sudo[3143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 16 19:00:52 rotel sudo[3143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:52 rotel sudo[3143]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:52 rotel sudo[2815]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:53 rotel volumio[1311]: verbose: New Socket.io Connection to 192.168.178.22 from 192.168.178.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:142.0) Gecko/20100101 Firefox/142.0 Engine version: 3 Transport: polling Total Clients: 5 Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 16 19:00:53 rotel volumio[1311]: info: CoreStateMachine::getcurrentVolume Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 19:00:53 rotel volumio[1311]: info: VolumeController:: Volume=100 Mute =false Sep 16 19:00:53 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:53 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:53 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:53 rotel volumio[1311]: info: CoreStateMachine::updateTrackBlock Sep 16 19:00:53 rotel volumio[1311]: info: CorePlayQueue::getTrackBlock Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 19:00:53 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:53 rotel volumio[1311]: info: Retrieving Cloud Streaming UI Sep 16 19:00:53 rotel volumio[1311]: info: Getting Tidal Cloud Configuration Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:53 rotel volumio[1311]: info: Getting Qobuz Cloud Configuration Sep 16 19:00:53 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:53 rotel volumio[1311]: info: Getting Spotify Cloud Configuration Sep 16 19:00:53 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:53 rotel volumio[1311]: info: Saving Spotify Acccount Sep 16 19:00:53 rotel volumio[1311]: info: Got it Sep 16 19:00:53 rotel volumio[1311]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 16 19:00:53 rotel volumio[1311]: info: Got Tidal Cloud Configuration Sep 16 19:00:53 rotel volumio[1311]: info: Got it Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:00:53 rotel volumio[1311]: info: VolumeController:: Volume=100 Mute =false Sep 16 19:00:53 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:00:53 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:00:53 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:00:53 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:00:53 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:00:54 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:00:55 rotel sudo[3151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Sep 16 19:00:55 rotel sudo[3151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:55 rotel sudo[3153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Sep 16 19:00:55 rotel sudo[3153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:55 rotel sudo[3153]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:55 rotel volumio[1311]: info: Internal perms successfully set Sep 16 19:00:55 rotel sudo[3151]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:55 rotel volumio[1311]: info: smb.conf Permissions set Sep 16 19:00:55 rotel sudo[3157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Sep 16 19:00:55 rotel sudo[3157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:55 rotel systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Sep 16 19:00:55 rotel nmbd[1223]: [2025/09/16 19:00:55.154195, 0] ../../source3/nmbd/nmbd.c:59(terminate) Sep 16 19:00:55 rotel nmbd[1223]: Got SIGTERM: going down... Sep 16 19:00:55 rotel systemd[1]: nmbd.service: Deactivated successfully. Sep 16 19:00:55 rotel systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Sep 16 19:00:55 rotel sudo[3161]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 19:00:55 rotel sudo[3161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:55 rotel sudo[3163]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 19:00:55 rotel sudo[3163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:55 rotel sudo[3163]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:55 rotel sudo[3161]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:55 rotel systemd[1]: Starting nmbd.service - Samba NMB Daemon... Sep 16 19:00:55 rotel sudo[3167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 16 19:00:55 rotel sudo[3167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:55 rotel systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Sep 16 19:00:55 rotel sudo[3167]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:55 rotel volumio[1311]: info: Upmpdcli Daemon Started Sep 16 19:00:55 rotel volumio[1311]: info: TidalConnect service started! Sep 16 19:00:55 rotel upmpdcli[3187]: writing RSA key Sep 16 19:00:55 rotel nmbd[3195]: [2025/09/16 19:00:55.304832, 0] ../../source3/nmbd/nmbd.c:901(main) Sep 16 19:00:55 rotel nmbd[3195]: nmbd version 4.17.8-Raspbian started. Sep 16 19:00:55 rotel nmbd[3195]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Sep 16 19:00:55 rotel nmbd[3195]: [2025/09/16 19:00:55.306155, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 16 19:00:55 rotel nmbd[3195]: started asyncdns process 3196 Sep 16 19:00:55 rotel systemd[1]: Started nmbd.service - Samba NMB Daemon. Sep 16 19:00:55 rotel sudo[3157]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:55 rotel sudo[3198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Sep 16 19:00:55 rotel sudo[3198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:00:55 rotel systemd[1]: Stopping smbd.service - Samba SMB Daemon... Sep 16 19:00:55 rotel systemd[1]: smbd.service: Deactivated successfully. Sep 16 19:00:55 rotel systemd[1]: Stopped smbd.service - Samba SMB Daemon. Sep 16 19:00:55 rotel systemd[1]: Starting smbd.service - Samba SMB Daemon... Sep 16 19:00:55 rotel volumio[1311]: info: TidalConnect service started! Sep 16 19:00:55 rotel smbd[3203]: [2025/09/16 19:00:55.422070, 0] ../../source3/smbd/server.c:1741(main) Sep 16 19:00:55 rotel smbd[3203]: smbd version 4.17.8-Raspbian started. Sep 16 19:00:55 rotel smbd[3203]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Sep 16 19:00:55 rotel volumio[1311]: info: TidalConnect service started! Sep 16 19:00:55 rotel systemd[1]: Started smbd.service - Samba SMB Daemon. Sep 16 19:00:55 rotel sudo[3198]: pam_unix(sudo:session): session closed for user root Sep 16 19:00:55 rotel volumio[1311]: info: SAMBA Restarted Sep 16 19:00:55 rotel volumio[1311]: info: TidalConnect service started! Sep 16 19:00:55 rotel volumio[1311]: info: TidalConnect service started! Sep 16 19:01:03 rotel volumio[1311]: info: Received OAUTH Data Sep 16 19:01:03 rotel volumio[1311]: info: Refreshing TIDAL token Sep 16 19:01:04 rotel volumio[1311]: info: TIDAL Access Token successfully retrieved Sep 16 19:01:04 rotel sudo[3228]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 19:01:04 rotel sudo[3228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:04 rotel sudo[3230]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 19:01:04 rotel sudo[3230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:04 rotel sudo[3228]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:04 rotel sudo[3230]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:04 rotel volumio[1311]: verbose: New Socket.io Connection to 192.168.178.22 from 192.168.178.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:142.0) Gecko/20100101 Firefox/142.0 Engine version: 3 Transport: polling Total Clients: 5 Sep 16 19:01:04 rotel volumio[1311]: info: Successfully retrieved User Session From TIDAL Sep 16 19:01:04 rotel volumio[1311]: info: Adding TIDAL to Browse Sources Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 19:01:04 rotel volumio[1311]: info: [1758042064295] CoreMusicLibrary::Adding element TIDAL Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:01:04 rotel volumio[1311]: Cannot find translation for source TIDAL Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Sep 16 19:01:04 rotel volumio[1311]: info: Received Get System Info Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 19:01:04 rotel volumio[1311]: info: Discovery: Getting this device information Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 19:01:04 rotel volumio-remote-updater[954]: Test mode disabled Sep 16 19:01:04 rotel volumio-remote-updater[954]: Alpha mode disabled Sep 16 19:01:04 rotel volumio-remote-updater[954]: Alpha legacy test mode disabled Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Retrieving Cloud Streaming UI Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Retrieving Cloud Streaming UI Sep 16 19:01:04 rotel volumio[1311]: info: Getting Tidal Cloud Configuration Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Getting Qobuz Cloud Configuration Sep 16 19:01:04 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Getting Spotify Cloud Configuration Sep 16 19:01:04 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Saving Spotify Acccount Sep 16 19:01:04 rotel volumio[1311]: info: Got it Sep 16 19:01:04 rotel volumio[1311]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 16 19:01:04 rotel volumio[1311]: info: Getting Tidal Cloud Configuration Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Getting Qobuz Cloud Configuration Sep 16 19:01:04 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Getting Spotify Cloud Configuration Sep 16 19:01:04 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Saving Spotify Acccount Sep 16 19:01:04 rotel volumio[1311]: info: Got it Sep 16 19:01:04 rotel volumio[1311]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 16 19:01:04 rotel volumio[1311]: info: Got Tidal Cloud Configuration Sep 16 19:01:04 rotel volumio[1311]: info: Got it Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Got Tidal Cloud Configuration Sep 16 19:01:04 rotel volumio[1311]: info: Got it Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:04 rotel volumio[1311]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 16 19:01:04 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 16 19:01:04 rotel volumio[1311]: info: Successfully retrieved User Subscription From TIDAL Sep 16 19:01:05 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 16 19:01:05 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 19:01:05 rotel volumio-remote-updater[954]: Test mode disabled Sep 16 19:01:05 rotel volumio-remote-updater[954]: Alpha mode disabled Sep 16 19:01:05 rotel volumio-remote-updater[954]: Alpha legacy test mode disabled Sep 16 19:01:05 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 16 19:01:05 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 16 19:01:05 rotel volumio[1311]: info: Loading i18n strings for locale pl Sep 16 19:01:05 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 16 19:01:05 rotel volumio[1311]: info: Setting timezone to Europe/Warsaw Sep 16 19:01:05 rotel sudo[3241]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 16 19:01:05 rotel sudo[3241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:05 rotel sudo[3241]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:05 rotel sudo[3245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Warsaw /etc/localtime Sep 16 19:01:05 rotel sudo[3245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:05 rotel sudo[3245]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:05 rotel sudo[3249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 16 19:01:05 rotel sudo[3249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:05 rotel sudo[3249]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:05 rotel sudo[3255]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Warsaw Sep 16 19:01:05 rotel sudo[3255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:05 rotel sudo[3255]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:05 rotel volumio[1311]: Updating browse sources language Sep 16 19:01:05 rotel volumio[1311]: Cannot find translation for source TIDAL Sep 16 19:01:05 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:01:05 rotel volumio[1311]: Cannot find translation for source TIDAL Sep 16 19:01:05 rotel volumio[1311]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 16 19:01:05 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 16 19:01:05 rotel volumio[1311]: info: Fetching Streaming Services browse cache Sep 16 19:01:06 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:01:06 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:01:07 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 16 19:01:07 rotel volumio[1311]: info: Loading i18n strings for locale pl Sep 16 19:01:07 rotel volumio[1311]: Updating browse sources language Sep 16 19:01:07 rotel volumio[1311]: Cannot find translation for source TIDAL Sep 16 19:01:07 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:01:07 rotel volumio[1311]: Cannot find translation for source TIDAL Sep 16 19:01:07 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 16 19:01:07 rotel volumio[1311]: info: Setting timezone to Europe/Warsaw Sep 16 19:01:07 rotel sudo[3265]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 16 19:01:07 rotel sudo[3265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:07 rotel sudo[3265]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:07 rotel sudo[3269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Warsaw /etc/localtime Sep 16 19:01:07 rotel sudo[3269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:07 rotel sudo[3269]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:07 rotel sudo[3273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 16 19:01:07 rotel sudo[3273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:07 rotel sudo[3273]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:07 rotel sudo[3277]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Warsaw Sep 16 19:01:07 rotel sudo[3277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:07 rotel sudo[3277]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:07 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 16 19:01:07 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 16 19:01:07 rotel sudo[3280]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 19:01:07 rotel sudo[3280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:07 rotel volumio[1311]: info: Fetching Streaming Services browse cache Sep 16 19:01:07 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 16 19:01:07 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 19:01:08 rotel volumio-remote-updater[954]: Test mode disabled Sep 16 19:01:08 rotel volumio-remote-updater[954]: Alpha mode disabled Sep 16 19:01:08 rotel volumio-remote-updater[954]: Alpha legacy test mode disabled Sep 16 19:01:08 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 16 19:01:08 rotel volumio[1311]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 16 19:01:08 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 16 19:01:09 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Sep 16 19:01:09 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Sep 16 19:01:09 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 16 19:01:09 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:01:09 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 16 19:01:10 rotel volumio[1311]: info: Preparing to save Alsa Options, stopping services first Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:10 rotel volumio[1311]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"Tone2"},"disallowPush":true} Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 19:01:10 rotel volumio[1311]: info: Setting mixer Tone2 for card Tone2 Sep 16 19:01:10 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:10 rotel vtcs[3080]: [2025-09-16 19:01:10.262] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 16 19:01:10 rotel vtcs[3080]: [2025-09-16 19:01:10.263] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Sep 16 19:01:10 rotel vtcs[3080]: [2025-09-16 19:01:10.263] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 16 19:01:10 rotel sudo[3296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:01:10 rotel sudo[3296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 16 19:01:10 rotel volumio[1311]: info: Updating Volume Controller Parameters: Device: 5 Name: Tone2 Mixer: Tone2 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 16 19:01:10 rotel volumio[1311]: info: Disabling external Volume Control Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:10 rotel volumio[1311]: info: Preparing to generate the ALSA configuration file Sep 16 19:01:10 rotel systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Sep 16 19:01:10 rotel systemd[1]: vtcs.service: Killing process 3088 (vtcs) with signal SIGKILL. Sep 16 19:01:10 rotel systemd[1]: vtcs.service: Deactivated successfully. Sep 16 19:01:10 rotel systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 16 19:01:10 rotel sudo[3296]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 16 19:01:10 rotel sudo[3304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:01:10 rotel sudo[3304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 16 19:01:10 rotel sudo[3311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:01:10 rotel sudo[3311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel volumio[1311]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 16 19:01:10 rotel volumio[1311]: info: Reading ALSA contributions from plugins. Sep 16 19:01:10 rotel sudo[3315]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 19:01:10 rotel sudo[3313]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 16 19:01:10 rotel sudo[3315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel sudo[3313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel sudo[3315]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel sudo[3313]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel volumio[1311]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 16 19:01:10 rotel volumio[1311]: wlan0 Interface doesn't support scanning : Device or resource busy Sep 16 19:01:10 rotel volumio[1311]: info: Cannot use regular scanning, forcing with ap-force Sep 16 19:01:10 rotel sudo[3304]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel sudo[3311]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel sudo[3322]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 16 19:01:10 rotel sudo[3322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel sudo[3322]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel sudo[3324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:01:10 rotel sudo[3324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel volumio[1311]: command failed: Device or resource busy (-16) Sep 16 19:01:10 rotel volumio[1311]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 16 19:01:10 rotel volumio[1311]: command failed: Device or resource busy (-16) Sep 16 19:01:10 rotel volumio[1311]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 16 19:01:10 rotel volumio[1311]: wlan0 Interface doesn't support scanning : Device or resource busy Sep 16 19:01:10 rotel volumio[1311]: info: Cannot use regular scanning, forcing with ap-force Sep 16 19:01:10 rotel sudo[3329]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 16 19:01:10 rotel sudo[3329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel sudo[3329]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel volumio[1311]: command failed: Device or resource busy (-16) Sep 16 19:01:10 rotel volumio[1311]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 16 19:01:10 rotel volumio[1311]: command failed: Device or resource busy (-16) Sep 16 19:01:10 rotel systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Sep 16 19:01:10 rotel volumio[1311]: info: VolumeController:: Volume=100 Mute =false Sep 16 19:01:10 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:01:10 rotel qobuz-connect[3065]: 20250916 19:01:10.346 [3065.3065] INFO SampleApp: Stopping Local configuration server Sep 16 19:01:10 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:01:10 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 16 19:01:10 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:01:10 rotel volumio[1311]: info: Asound.conf file unchanged, so no further update is needed Sep 16 19:01:10 rotel volumio[1311]: info: Output device has changed, restarting MPD Sep 16 19:01:10 rotel volumio[1311]: info: Output device has changed, restarting Shairport Sync Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:01:10 rotel sudo[3338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 19:01:10 rotel sudo[3338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel sudo[3338]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 19:01:10 rotel volumio[1311]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 19:01:10 rotel sudo[3340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel sudo[3340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:10 rotel sudo[3350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:01:10 rotel sudo[3350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 16 19:01:10 rotel sudo[3352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 19:01:10 rotel sudo[3352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel systemd[1]: mpd.service: Deactivated successfully. Sep 16 19:01:10 rotel systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 16 19:01:10 rotel volumio[1311]: info: MPD Permissions set Sep 16 19:01:10 rotel systemd[1]: mpd.socket: Deactivated successfully. Sep 16 19:01:10 rotel systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 16 19:01:10 rotel systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 16 19:01:10 rotel sudo[3360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel sudo[3360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:10 rotel volumio[1311]: info: Starting Shairport Sync Sep 16 19:01:10 rotel systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 16 19:01:10 rotel systemd[1]: Starting mpd.service - Music Player Daemon... Sep 16 19:01:10 rotel sudo[3369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 19:01:10 rotel sudo[3369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel sudo[3350]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel sudo[3360]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 16 19:01:10 rotel sudo[3372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 19:01:10 rotel sudo[3372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:10 rotel sudo[3352]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel sudo[3369]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel sudo[3370]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 19:01:10 rotel sudo[3370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 19:01:10 rotel sudo[3370]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel volumio[1311]: info: Shairport-Sync Started Sep 16 19:01:10 rotel volumio[1311]: (node:1311) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Sep 16 19:01:10 rotel volumio[1311]: (Use `node --trace-warnings ...` to show where the warning was created) Sep 16 19:01:10 rotel volumio[1311]: (node:1311) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Sep 16 19:01:10 rotel volumio[1311]: (node:1311) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Sep 16 19:01:10 rotel volumio[1311]: (node:1311) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Sep 16 19:01:10 rotel volumio[1311]: (node:1311) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Sep 16 19:01:10 rotel mpd[3386]: 2025-09-16T19:01:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 19:01:10 rotel systemd[1]: Started mpd.service - Music Player Daemon. Sep 16 19:01:10 rotel sudo[3340]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:10 rotel volumio[1311]: error: updateQueue error: null Sep 16 19:01:10 rotel sudo[3280]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:11 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:11 rotel volumio[1311]: info: Retrieving Cloud Streaming UI Sep 16 19:01:11 rotel volumio[1311]: info: Getting Tidal Cloud Configuration Sep 16 19:01:11 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:11 rotel volumio[1311]: info: Getting Qobuz Cloud Configuration Sep 16 19:01:11 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:01:11 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:11 rotel volumio[1311]: info: Getting Spotify Cloud Configuration Sep 16 19:01:11 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:01:11 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:11 rotel volumio[1311]: info: Saving Spotify Acccount Sep 16 19:01:11 rotel volumio[1311]: info: Got it Sep 16 19:01:11 rotel volumio[1311]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 16 19:01:11 rotel volumio[1311]: info: Got it Sep 16 19:01:11 rotel volumio[1311]: info: Got Tidal Cloud Configuration Sep 16 19:01:11 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:11 rotel qobuz-connect[3065]: 20250916 19:01:11.643 [3065.3065] INFO SampleApp: shat down connection on UNIX socket Sep 16 19:01:11 rotel volumio[1311]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 16 19:01:11 rotel volumio[1311]: info: QobuzConnect: setDeactiveState invoked Sep 16 19:01:11 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:11 rotel systemd[1]: qobuz-connect.service: Deactivated successfully. Sep 16 19:01:11 rotel systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:01:11 rotel systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Sep 16 19:01:11 rotel sudo[3372]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:11 rotel sudo[3324]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:11 rotel volumio[1311]: info: Executing endpoint qc_getconfig Sep 16 19:01:11 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 16 19:01:11 rotel qobuz-connect[3410]: 20250916 19:01:11.687 [3410.3410] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 16 19:01:11 rotel volumio[1311]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 16 19:01:11 rotel volumio[1311]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 16 19:01:11 rotel qobuz-connect[3410]: 20250916 19:01:11.689 [3410.3410] INFO VolumeManager: [0xf34368]: Setting new playback volume: 75 Sep 16 19:01:11 rotel qobuz-connect[3410]: 20250916 19:01:11.689 [3410.3410] INFO VolumeManager: [0xf34368]: Setting new mute state: 0 Sep 16 19:01:11 rotel qobuz-connect[3410]: 20250916 19:01:11.689 [3410.3410] INFO QobuzConnect: [0xf34d38]: Client initialized! Sep 16 19:01:11 rotel qobuz-connect[3410]: 20250916 19:01:11.689 [3410.3410] INFO SampleApp: Starting Avahi advertising, name: Rotel, service name: _qobuz-connect._tcp Sep 16 19:01:11 rotel qobuz-connect[3410]: 20250916 19:01:11.693 [3410.3410] INFO LocalConfigManager: [0xf33c48]: Starting Local Configuration server Sep 16 19:01:11 rotel qobuz-connect[3410]: 20250916 19:01:11.693 [3410.3410] INFO SampleApp: Starting Local configuration server Sep 16 19:01:11 rotel qobuz-connect[3410]: 20250916 19:01:11.693 [3410.3410] INFO SampleApp: Connected to UNIX socket client 0xf1e818 Sep 16 19:01:11 rotel qobuz-connect[3410]: 20250916 19:01:11.837 [3410.3410] INFO SampleApp: Playback volume changed: 75 Sep 16 19:01:11 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:12 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Sep 16 19:01:12 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Sep 16 19:01:12 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Sep 16 19:01:12 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Sep 16 19:01:12 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::Reload Ui Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Sep 16 19:01:13 rotel volumio[1311]: info: Wizard terminated Successfully Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumiosetStartupVolume Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 19:01:13 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:01:13 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:01:13 rotel volumio[1311]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:01:13 rotel volumio[1311]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:01:13 rotel sudo[3432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 16 19:01:13 rotel sudo[3432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:13 rotel systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 16 19:01:13 rotel sudo[3432]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:13 rotel volumio[1311]: info: Executing endpoint tc_getconfig Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 16 19:01:13 rotel vtcs[3435]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 16 19:01:13 rotel vtcs[3435]: STARTED TidalConnect services. Sep 16 19:01:13 rotel volumio[1311]: info: Executing endpoint tc_connect Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 16 19:01:13 rotel volumio[1311]: info: Connecting to TidalConnect Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::servicePushState Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:13 rotel volumio[1311]: info: CorePlayQueue::getTrack 0 Sep 16 19:01:13 rotel volumio[1311]: 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} Sep 16 19:01:13 rotel volumio[1311]: verbose: CURRENT POSITION 0 Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::syncState stateService stop Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::syncState currentStatus stop Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:13 rotel volumio[1311]: info: No code Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::servicePushState Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:13 rotel volumio[1311]: info: CorePlayQueue::getTrack 0 Sep 16 19:01:13 rotel volumio[1311]: 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} Sep 16 19:01:13 rotel volumio[1311]: verbose: CURRENT POSITION 0 Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::syncState stateService stop Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::syncState currentStatus stop Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:13 rotel volumio[1311]: info: No code Sep 16 19:01:13 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:01:13 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:01:13 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:13 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:01:13 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:01:13 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:01:13 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:01:13 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:01:13 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:01:13 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:01:13 rotel volumio[1311]: info: TidalConnect service stoped! Sep 16 19:01:13 rotel volumio[1311]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:01:13 rotel volumio[1311]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 16 19:01:13 rotel sudo[3462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 16 19:01:13 rotel sudo[3462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:13 rotel sudo[3462]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:13 rotel sudo[3466]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 19:01:13 rotel sudo[3466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:13 rotel sudo[3466]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:13 rotel sudo[3468]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 19:01:13 rotel sudo[3468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:13 rotel sudo[3468]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:13 rotel volumio[1311]: verbose: New Socket.io Connection to 192.168.178.22 from 192.168.178.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:142.0) Gecko/20100101 Firefox/142.0 Engine version: 3 Transport: polling Total Clients: 5 Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 16 19:01:14 rotel volumio[1311]: info: CoreStateMachine::getcurrentVolume Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 19:01:14 rotel volumio[1311]: info: VolumeController:: Volume=100 Mute =false Sep 16 19:01:14 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:01:14 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:01:14 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:14 rotel volumio[1311]: info: CoreStateMachine::updateTrackBlock Sep 16 19:01:14 rotel volumio[1311]: info: CorePlayQueue::getTrackBlock Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 19:01:14 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:01:14 rotel volumio[1311]: info: VolumeController:: Volume=100 Mute =false Sep 16 19:01:14 rotel volumio[1311]: info: CoreStateMachine::pushState Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioPushState Sep 16 19:01:14 rotel volumio[1311]: info: MRS: Pushing multiroomSync output update for this device Sep 16 19:01:14 rotel volumio[1311]: info: MRS: Pushing multiroomSync output Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:14 rotel volumio[1311]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ARxqY3GP1WVyHjEzcEr18bcDCNc2.f9f2ad1a6ee8b0f949dd1b9fa7fc1a0d.state.status' Sep 16 19:01:14 rotel sudo[3476]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 19:01:14 rotel sudo[3476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:14 rotel sudo[3476]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:14 rotel sudo[3478]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 19:01:14 rotel sudo[3478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:14 rotel sudo[3478]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:14 rotel volumio[1311]: verbose: New Socket.io Connection to 192.168.178.22 from 192.168.178.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:142.0) Gecko/20100101 Firefox/142.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 16 19:01:14 rotel volumio[1311]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 16 19:01:14 rotel volumio[1311]: info: Received Get System Info Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 19:01:14 rotel volumio[1311]: info: Discovery: Getting this device information Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:14 rotel volumio[1311]: info: Listing playlists Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 16 19:01:14 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 16 19:01:15 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:16 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 16 19:01:16 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:01:16 rotel volumio[1311]: info: Received Get System Info Sep 16 19:01:16 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 19:01:16 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 19:01:16 rotel volumio[1311]: info: Discovery: Getting this device information Sep 16 19:01:16 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:16 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 19:01:16 rotel volumio[1311]: info: TidalConnect service started! Sep 16 19:01:16 rotel volumio[1311]: info: TidalConnect service started! Sep 16 19:01:17 rotel volumio[1311]: info: Executing endpoint metavolumio Sep 16 19:01:17 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 16 19:01:17 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:01:17 rotel volumio[1311]: info: Received Get System Info Sep 16 19:01:17 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 19:01:17 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 19:01:17 rotel volumio[1311]: info: Discovery: Getting this device information Sep 16 19:01:17 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:17 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 19:01:18 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Sep 16 19:01:18 rotel volumio[1311]: error: Failed request for metavolumio API Sep 16 19:01:20 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:20 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 19:01:20 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 19:01:20 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 19:01:20 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 16 19:01:20 rotel volumio[1311]: info: Received Get System Version Sep 16 19:01:20 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 19:01:24 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 16 19:01:26 rotel volumio[1311]: info: CoreCommandRouter::Close All Modals sent Sep 16 19:01:26 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Sep 16 19:01:26 rotel volumio[1311]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Sep 16 19:01:26 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Sep 16 19:01:26 rotel volumio[1311]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"} Sep 16 19:01:27 rotel volumio[1311]: info: CoreCommandRouter::Reload Ui Sep 16 19:01:28 rotel sudo[3528]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 19:01:28 rotel sudo[3528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:28 rotel sudo[3528]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:28 rotel sudo[3531]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 19:01:28 rotel sudo[3531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:28 rotel sudo[3531]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:28 rotel volumio[1311]: verbose: New Socket.io Connection to 192.168.178.22 from 192.168.178.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:142.0) Gecko/20100101 Firefox/142.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 16 19:01:28 rotel volumio[1311]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 16 19:01:28 rotel volumio[1311]: info: Received Get System Version Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 19:01:28 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Sep 16 19:01:30 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:01:30 rotel volumio[1311]: info: Received Get System Info Sep 16 19:01:30 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 19:01:30 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 19:01:30 rotel volumio[1311]: info: Discovery: Getting this device information Sep 16 19:01:30 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:30 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 19:01:31 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 19:01:31 rotel volumio[1311]: info: Received Get System Info Sep 16 19:01:31 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 19:01:31 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 19:01:31 rotel volumio[1311]: info: Discovery: Getting this device information Sep 16 19:01:31 rotel volumio[1311]: info: CoreCommandRouter::volumioGetState Sep 16 19:01:31 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:32 rotel volumio[1311]: info: Retrieving Cloud Streaming UI Sep 16 19:01:32 rotel volumio[1311]: info: Getting Tidal Cloud Configuration Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:32 rotel volumio[1311]: info: Getting Qobuz Cloud Configuration Sep 16 19:01:32 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:32 rotel volumio[1311]: info: Getting Spotify Cloud Configuration Sep 16 19:01:32 rotel volumio[1311]: info: Asking plugin for UI Config Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:32 rotel volumio[1311]: info: Saving Spotify Acccount Sep 16 19:01:32 rotel volumio[1311]: info: Got it Sep 16 19:01:32 rotel volumio[1311]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 16 19:01:32 rotel volumio[1311]: info: Got Tidal Cloud Configuration Sep 16 19:01:32 rotel volumio[1311]: info: Got it Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::volumioGetBrowseSources Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::volumioGetBrowseSources Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::volumioGetBrowseSources Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 16 19:01:32 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 16 19:01:34 rotel volumio[1311]: info: Disabling MyMusic plugin upnp Sep 16 19:01:34 rotel sudo[3565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 16 19:01:34 rotel sudo[3565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 16 19:01:34 rotel systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 16 19:01:34 rotel systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Sep 16 19:01:34 rotel setdatetime-helper.sh[3567]: Time is not synchronized. Attempting to sync... Sep 16 19:01:34 rotel sudo[3576]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 16 Sep 2025 17:01:34 GMT#015' Sep 16 19:01:34 rotel sudo[3576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 19:01:34 rotel setdatetime-helper.sh[3577]: Tue Sep 16 19:01:34 CEST 2025 Sep 16 19:01:34 rotel sudo[3576]: pam_unix(sudo:session): session closed for user root Sep 16 19:01:34 rotel systemd-journald[422]: Time jumped backwards, rotating. Sep 16 19:01:34 rotel setdatetime-helper.sh[3567]: Time synchronized successfully. Sep 16 19:01:34 rotel systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 16 19:01:34 rotel systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 16 19:01:34 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 16 19:01:34 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 19:01:34 rotel volumio-remote-updater[954]: Test mode disabled Sep 16 19:01:34 rotel volumio-remote-updater[954]: Alpha mode disabled Sep 16 19:01:34 rotel volumio-remote-updater[954]: Alpha legacy test mode disabled Sep 16 19:01:34 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 16 19:01:34 rotel volumio[1311]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 16 19:01:34 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 16 19:01:35 rotel volumio[1311]: info: Enabling MyMusic plugin upnp Sep 16 19:01:35 rotel volumio[1311]: info: Enabling plugin upnp Sep 16 19:01:35 rotel volumio[1311]: info: Loading plugin "upnp"... Sep 16 19:01:35 rotel volumio[1311]: info: [1758042095243] Starting Upmpd Daemon Sep 16 19:01:35 rotel volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 19:01:35 rotel volumio[1311]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 19:01:35 rotel volumio[1311]: Error: listen EADDRINUSE: address already in use :::6599 Sep 16 19:01:35 rotel volumio[1311]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Sep 16 19:01:35 rotel volumio[1311]: at listenInCluster (node:net:1920:12) Sep 16 19:01:35 rotel volumio[1311]: at Server.listen (node:net:2008:7) Sep 16 19:01:35 rotel volumio[1311]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 16 19:01:35 rotel volumio[1311]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 16 19:01:35 rotel volumio[1311]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 16 19:01:35 rotel volumio[1311]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 16 19:01:35 rotel volumio[1311]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Sep 16 19:01:35 rotel volumio[1311]: code: 'EADDRINUSE', Sep 16 19:01:35 rotel volumio[1311]: errno: -98, Sep 16 19:01:35 rotel volumio[1311]: syscall: 'listen', Sep 16 19:01:35 rotel volumio[1311]: address: '::', Sep 16 19:01:35 rotel volumio[1311]: port: 6599 Sep 16 19:01:35 rotel volumio[1311]: } Sep 16 19:01:35 rotel volumio[1311]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 19:01:35 rotel sudo[3595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-16 19:00' Sep 16 19:01:35 rotel sudo[3595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"