Dec 26 15:35:09 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 26 15:35:09 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 15:35:09 volumio volumio-remote-updater[967]: Test mode disabled Dec 26 15:35:09 volumio volumio-remote-updater[967]: Alpha mode disabled Dec 26 15:35:09 volumio volumio-remote-updater[967]: Alpha legacy test mode disabled Dec 26 15:35:09 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 26 15:35:09 volumio volumio[1317]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 15:35:09 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 15:35:13 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 26 15:35:13 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 15:35:13 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 26 15:35:13 volumio volumio-remote-updater[967]: Test mode disabled Dec 26 15:35:13 volumio volumio-remote-updater[967]: Alpha mode disabled Dec 26 15:35:13 volumio volumio-remote-updater[967]: Alpha legacy test mode disabled Dec 26 15:35:13 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 26 15:35:13 volumio volumio[1317]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 15:35:13 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 15:35:43 volumio sudo[2780]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 15:35:43 volumio sudo[2780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:43 volumio sudo[2780]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:43 volumio sudo[2782]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 15:35:43 volumio sudo[2782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:43 volumio sudo[2782]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:43 volumio volumio[1317]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 26 15:35:43 volumio volumio[1317]: info: Received Get System Info Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 15:35:43 volumio volumio[1317]: info: Discovery: Getting this device information Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:43 volumio volumio[1317]: info: CorePlayQueue::getTrack 0 Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 15:35:43 volumio volumio-remote-updater[967]: Test mode disabled Dec 26 15:35:43 volumio volumio-remote-updater[967]: Alpha mode disabled Dec 26 15:35:43 volumio volumio-remote-updater[967]: Alpha legacy test mode disabled Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 15:35:43 volumio volumio[1317]: info: Retrieving Cloud Streaming UI Dec 26 15:35:43 volumio volumio[1317]: info: Getting Tidal Cloud Configuration Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 15:35:43 volumio volumio[1317]: info: Getting Qobuz Cloud Configuration Dec 26 15:35:43 volumio volumio[1317]: info: Asking plugin for UI Config Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 15:35:43 volumio volumio[1317]: info: Getting Spotify Cloud Configuration Dec 26 15:35:43 volumio volumio[1317]: info: Asking plugin for UI Config Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 15:35:43 volumio volumio[1317]: info: Saving Spotify Acccount Dec 26 15:35:43 volumio volumio[1317]: info: Got Tidal Cloud Configuration Dec 26 15:35:43 volumio volumio[1317]: info: Got it Dec 26 15:35:43 volumio volumio[1317]: info: Got it Dec 26 15:35:43 volumio volumio[1317]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 15:35:43 volumio volumio[1317]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 26 15:35:43 volumio volumio[1317]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 15:35:43 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 15:35:44 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 26 15:35:44 volumio volumio[1317]: info: Loading i18n strings for locale fr Dec 26 15:35:44 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 26 15:35:44 volumio volumio[1317]: info: Setting timezone to Europe/Paris Dec 26 15:35:44 volumio sudo[2792]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 26 15:35:44 volumio sudo[2792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:44 volumio sudo[2792]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:44 volumio sudo[2796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Paris /etc/localtime Dec 26 15:35:44 volumio sudo[2796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:44 volumio sudo[2796]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:44 volumio sudo[2801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 26 15:35:44 volumio sudo[2801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:44 volumio sudo[2801]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:44 volumio sudo[2805]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Paris Dec 26 15:35:44 volumio sudo[2805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:44 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Dec 26 15:35:44 volumio dbus-daemon[958]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.29' (uid=0 pid=2806 comm="/usr/bin/timedatectl set-timezone Europe/Paris") Dec 26 15:35:44 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 26 15:35:45 volumio dbus-daemon[958]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 26 15:35:45 volumio sudo[2805]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:45 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 26 15:35:45 volumio volumio[1317]: Updating browse sources language Dec 26 15:35:45 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 15:35:45 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Dec 26 15:35:45 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Dec 26 15:35:45 volumio volumio[1317]: info: Fetching Streaming Services browse cache Dec 26 15:35:46 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 26 15:35:46 volumio volumio[1317]: info: MyVolumio login type: Token Dec 26 15:35:46 volumio volumio[1317]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 26 15:35:46 volumio volumio[1317]: info: MyVolumio token set successfully Dec 26 15:35:46 volumio volumio[1317]: info: MYVOLUMIO: Adding device Dec 26 15:35:46 volumio volumio[1317]: info: MYVOLUMIO: Evaluating Server Dec 26 15:35:46 volumio volumio[1317]: info: MyVolumio Plan changed: superstar Dec 26 15:35:46 volumio volumio[1317]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Dec 26 15:35:46 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:46 volumio volumio[1317]: info: MYVOLUMIO: Adding device Dec 26 15:35:46 volumio volumio[1317]: info: MYVOLUMIO: Evaluating Server Dec 26 15:35:46 volumio volumio[1317]: info: Remote config written successfully Dec 26 15:35:46 volumio volumio[1317]: info: Starting Tunnel 1 Dec 26 15:35:46 volumio volumio[1317]: info: Starting Tunnel Connection Checker Dec 26 15:35:47 volumio volumio[1317]: info: MYVolumio Device enabled Dec 26 15:35:47 volumio volumio[1317]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 26 15:35:47 volumio volumio[1317]: info: MyVolumio status changed Dec 26 15:35:47 volumio volumio[1317]: info: Streaming services startup Dec 26 15:35:47 volumio volumio[1317]: info: Starting Streaming Daemon Dec 26 15:35:47 volumio sudo[2917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 15:35:47 volumio sudo[2917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:47 volumio volumio[1317]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Dec 26 15:35:47 volumio sudo[2917]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:47 volumio volumio[1317]: error: Cannot start Volumio Streaming Daemon Dec 26 15:35:47 volumio volumio[1317]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 15:35:47 volumio volumio[1317]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 15:35:47 volumio volumio[1317]: info: Setting Geolocation for MyVolumio to eu4 Dec 26 15:35:47 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:47 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:47 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:47 volumio volumio[1317]: info: Setting Geolocation for MyVolumio to eu4 Dec 26 15:35:47 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:47 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:47 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:47 volumio volumio[1317]: info: Successfully Added MyVolumio device Dec 26 15:35:47 volumio volumio[1317]: info: Successfully Added MyVolumio device Dec 26 15:35:47 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 26 15:35:47 volumio volumio[1317]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 26 15:35:47 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 26 15:35:48 volumio sudo[2921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 26 15:35:48 volumio sudo[2921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio sudo[2921]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio volumio[1317]: info: MRS: MultiRoom plugin initialized Dec 26 15:35:48 volumio volumio[1317]: info: MRS: STOPPING SNAPCLIENT Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Snap server stop Dec 26 15:35:48 volumio volumio[1317]: info: MRS: STOPPING volumioStreaming Dec 26 15:35:48 volumio sudo[2938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 26 15:35:48 volumio sudo[2938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 26 15:35:48 volumio sudo[2945]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 26 15:35:48 volumio sudo[2945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio sudo[2941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 26 15:35:48 volumio sudo[2941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio sudo[2945]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio sudo[2944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 26 15:35:48 volumio sudo[2944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 26 15:35:48 volumio sudo[2938]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 26 15:35:48 volumio sudo[2941]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio sudo[2944]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 26 15:35:48 volumio volumio[1317]: info: Preparing to generate the ALSA configuration file Dec 26 15:35:48 volumio volumio[1317]: info: Updating MyVolumio device info Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: Updating MyVolumio device info Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Removed streaming files Dec 26 15:35:48 volumio volumio[1317]: info: MRS: volumioStreaming STOPPED Dec 26 15:35:48 volumio volumio[1317]: info: MRS: SNAPSERVER STOPPED Dec 26 15:35:48 volumio volumio[1317]: info: MRS: SNAPCLIENT STOPPED Dec 26 15:35:48 volumio volumio[1317]: info: Asound.conf file unchanged, so no further update is needed Dec 26 15:35:48 volumio volumio[1317]: info: Output device has changed, restarting MPD Dec 26 15:35:48 volumio volumio[1317]: info: Output device has changed, restarting Shairport Sync Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:48 volumio sudo[2950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 15:35:48 volumio sudo[2950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio sudo[2952]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 15:35:48 volumio sudo[2952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio sudo[2950]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio volumio[1317]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 26 15:35:48 volumio volumio[1317]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 26 15:35:48 volumio volumio[1317]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 26 15:35:48 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 15:35:48 volumio volumio[1317]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 26 15:35:48 volumio volumio[1317]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 26 15:35:48 volumio volumio[1317]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Pushing multiroomSync output for this device Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:35:48 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:48 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 26 15:35:48 volumio volumio[1317]: info: Adding METAVOLUMIO REST API Endpoints Dec 26 15:35:48 volumio volumio[1317]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 26 15:35:48 volumio volumio[1317]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 26 15:35:48 volumio volumio[1317]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 26 15:35:48 volumio volumio[1317]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 26 15:35:48 volumio volumio[1317]: info: Preparing CD Folders Dec 26 15:35:48 volumio volumio[1317]: info: Adding CD REST API Endpoints Dec 26 15:35:48 volumio volumio[1317]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 26 15:35:48 volumio volumio[1317]: info: Starting UDEV Watcher for CD Dec 26 15:35:48 volumio volumio[1317]: info: Detecting CD presence with UDEV Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 26 15:35:48 volumio bluetoothd[955]: Path / reserved for Adv Monitor app :1.33 Dec 26 15:35:48 volumio bluetoothd[955]: Adv Monitor app :1.33 disconnected from D-Bus Dec 26 15:35:48 volumio volumio[1317]: info: [cd-plugin] Set CD speed to 1X Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 26 15:35:48 volumio volumio[1317]: info: Adding inputs REST Endpoints Dec 26 15:35:48 volumio volumio[1317]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 26 15:35:48 volumio volumio[1317]: info: Scanning Audio Inputs Dec 26 15:35:48 volumio volumio[1317]: info: Checking against Known Cards name Dec 26 15:35:48 volumio volumio[1317]: info: Adding Server instance for streaming Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 26 15:35:48 volumio volumio[1317]: error: Hi Res Audio Failed Login: Missing Login Data Dec 26 15:35:48 volumio volumio[1317]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 26 15:35:48 volumio volumio[1317]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 26 15:35:48 volumio volumio[1317]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 26 15:35:48 volumio volumio[1317]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 26 15:35:48 volumio volumio[1317]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 26 15:35:48 volumio sudo[2973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 26 15:35:48 volumio sudo[2973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio volumio[1317]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 26 15:35:48 volumio volumio[1317]: info: Adding TIDAL REST API Endpoints Dec 26 15:35:48 volumio volumio[1317]: info: Stopping AccessToken refresher cron for QOBUZ Dec 26 15:35:48 volumio sudo[2980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 26 15:35:48 volumio sudo[2980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio volumio[1317]: info: AccessToken refresher cron started for QOBUZ Dec 26 15:35:48 volumio volumio[1317]: info: Adding QOBUZ REST API Endpoints Dec 26 15:35:48 volumio sudo[2973]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio sudo[2980]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Adding multiroomSync output Dec 26 15:35:48 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:35:48 volumio sudo[2983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 26 15:35:48 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Adding multiroomSync output Dec 26 15:35:48 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:35:48 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 15:35:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 15:35:48 volumio systemd[1]: mpd.service: Consumed 1.221s CPU time. Dec 26 15:35:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 15:35:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 15:35:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 15:35:48 volumio volumio[1317]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 26 15:35:48 volumio volumio[1317]: info: MPD Permissions set Dec 26 15:35:48 volumio sudo[2987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 26 15:35:48 volumio sudo[2987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 15:35:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 15:35:48 volumio volumio[1317]: info: Successfully Updated MyVolumio device Dec 26 15:35:48 volumio volumio[1317]: info: Starting Shairport Sync Dec 26 15:35:48 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 26 15:35:48 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 26 15:35:48 volumio sudo[2987]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio sudo[3001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 15:35:48 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Found cast device: Chromecast-ce1e28c9ee406bc3edb4e4bc79fbccaf Dec 26 15:35:48 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:48 volumio volumio[1317]: info: Successfully Updated MyVolumio device Dec 26 15:35:48 volumio volumio[1317]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::volumioStop Dec 26 15:35:48 volumio volumio[1317]: info: CoreStateMachine::stop Dec 26 15:35:48 volumio volumio[1317]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 15:35:48 volumio volumio[1317]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 26 15:35:48 volumio volumio[1317]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 15:35:48 volumio volumio[1317]: info: Preparing to generate the ALSA configuration file Dec 26 15:35:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 15:35:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 15:35:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 15:35:48 volumio systemd[1]: shairport-sync.service: Consumed 1.618s CPU time. Dec 26 15:35:48 volumio sudo[2996]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 15:35:48 volumio sudo[2996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 15:35:48 volumio volumiobt[3005]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 26 15:35:48 volumio sudo[2996]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Found cast device: MiTV-AYFR0-59164b82a3ac55e9008c44334da4a1b5 Dec 26 15:35:48 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:48 volumio sudo[3008]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 26 15:35:48 volumio sudo[3008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio sudo[3008]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio volumio[1317]: info: Asound.conf file unchanged, so no further update is needed Dec 26 15:35:48 volumio volumio[1317]: info: Output device has changed, restarting MPD Dec 26 15:35:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 15:35:48 volumio sudo[3010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 26 15:35:48 volumio sudo[3010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio sudo[3010]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio volumiobt[3014]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 26 15:35:48 volumio sudo[3001]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio volumio[1317]: info: Output device has changed, restarting Shairport Sync Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:48 volumio sudo[3017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 15:35:48 volumio sudo[3017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio sudo[3017]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio sudo[3019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 15:35:48 volumio sudo[3019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio bluetoothd[955]: Adv Monitor app :1.39 disconnected from D-Bus Dec 26 15:35:48 volumio volumiobt[3039]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 26 15:35:48 volumio volumio[1317]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:48 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 15:35:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 15:35:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 15:35:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 15:35:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 15:35:48 volumio volumio[1317]: info: QobuzConnect: setDeactiveState invoked Dec 26 15:35:48 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:48 volumio volumio[1317]: info: Preparing to generate the ALSA configuration file Dec 26 15:35:48 volumio volumiobt[3041]: [176B blob data] Dec 26 15:35:48 volumio volumiobt[3041]: [157B blob data] Dec 26 15:35:48 volumio volumiobt[3041]: [157B blob data] Dec 26 15:35:48 volumio volumiobt[3041]: [157B blob data] Dec 26 15:35:48 volumio volumiobt[3041]: [113B blob data] Dec 26 15:35:48 volumio volumiobt[3041]: [bluetoothctl]> discoverable on Dec 26 15:35:48 volumio volumiobt[3041]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 26 15:35:48 volumio volumiobt[3041]: [bluetoothctl]> pairable on Dec 26 15:35:48 volumio bluetoothd[955]: Adv Monitor app :1.42 disconnected from D-Bus Dec 26 15:35:48 volumio volumiobt[3041]: [bluetoothctl]> Dec 26 15:35:48 volumio volumiobt[3049]: INFO [BTSTART] Registering Bluetooth agent... Dec 26 15:35:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Found cast device: SHIELD-Android-TV-9aae964c8c1a9b097086db1c592e8bf8 Dec 26 15:35:48 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Found cast device: SHIELD-Android-TV-103388cce4e4175cbcde7417bf51a828 Dec 26 15:35:48 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:48 volumio volumiobt[3050]: [NEW] Media /org/bluez/hci0 Dec 26 15:35:48 volumio volumiobt[3050]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 26 15:35:48 volumio volumiobt[3050]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 26 15:35:48 volumio volumiobt[3050]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 26 15:35:48 volumio volumio[1317]: info: MRS: Found cast device: BRAVIA-4K-UR2-19816630e96ac82710c1bccb85d62627 Dec 26 15:35:48 volumio volumio[1317]: info: Adding audio output: Dec 26 15:35:48 volumio bluetoothd[955]: Path / reserved for Adv Monitor app :1.43 Dec 26 15:35:48 volumio bluetoothd[955]: Adv Monitor app :1.43 disconnected from D-Bus Dec 26 15:35:48 volumio sudo[3052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 26 15:35:48 volumio sudo[3052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:48 volumio volumiobt[3054]: No agent is registered Dec 26 15:35:48 volumio volumiobt[3054]: [NEW] Media /org/bluez/hci0 Dec 26 15:35:48 volumio volumiobt[3054]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 26 15:35:48 volumio volumiobt[3054]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 26 15:35:48 volumio volumiobt[3054]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 26 15:35:48 volumio bluetoothd[955]: Adv Monitor app :1.44 disconnected from D-Bus Dec 26 15:35:48 volumio volumiobt[3056]: INFO [BTSTART] Agent registered successfully. Dec 26 15:35:48 volumio volumiobt[3057]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 26 15:35:48 volumio volumio[1317]: info: MPD Permissions set Dec 26 15:35:48 volumio volumio[1317]: info: Shairport-Sync Started Dec 26 15:35:48 volumio sudo[3052]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio sudo[3053]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 15:35:48 volumio sudo[3053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 15:35:48 volumio sudo[3059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 26 15:35:48 volumio sudo[3053]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:48 volumio sudo[3059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:49 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 26 15:35:49 volumio volumio[1317]: info: Executing endpoint qc_getconfig Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 26 15:35:49 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 26 15:35:49 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 26 15:35:49 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 26 15:35:49 volumio sudo[3059]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: Asound.conf file unchanged, so no further update is needed Dec 26 15:35:49 volumio volumio[1317]: info: Output device has changed, restarting MPD Dec 26 15:35:49 volumio volumio[1317]: info: Output device has changed, restarting Shairport Sync Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 15:35:49 volumio volumio[1317]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio sudo[3073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 15:35:49 volumio sudo[3073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:49 volumio volumio[1317]: info: QobuzConnect: setDeactiveState invoked Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:49 volumio sudo[3075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 15:35:49 volumio sudo[3075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:49 volumio sudo[3073]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio sudo[3089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 26 15:35:49 volumio volumio[1317]: info: Executing endpoint qc_getconfig Dec 26 15:35:49 volumio sudo[3089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 26 15:35:49 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 15:35:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 15:35:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 15:35:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 15:35:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 15:35:49 volumio volumio[1317]: info: MPD Permissions set Dec 26 15:35:49 volumio volumio[1317]: info: Starting Shairport Sync Dec 26 15:35:49 volumio qobuz-connect[3066]: 20251226 15:35:49.185 [3066.3066] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 26 15:35:49 volumio volumio[1317]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 26 15:35:49 volumio volumio[1317]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio sudo[3096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 15:35:49 volumio sudo[3096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 15:35:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:35:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 15:35:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 15:35:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 15:35:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 15:35:49 volumio sudo[3096]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio volumio[1317]: info: Starting Shairport Sync Dec 26 15:35:49 volumio sudo[3089]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio sudo[3101]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 15:35:49 volumio sudo[3101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 15:35:49 volumio volumio[1317]: info: Shairport-Sync Started Dec 26 15:35:49 volumio sudo[3101]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio sudo[3106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 26 15:35:49 volumio sudo[3106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:49 volumio sudo[3107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 15:35:49 volumio sudo[3107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:49 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 26 15:35:49 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 26 15:35:49 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 26 15:35:49 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 26 15:35:49 volumio sudo[3106]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio volumio[1317]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 26 15:35:49 volumio volumio[1317]: info: QobuzConnect: setDeactiveState invoked Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 15:35:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 15:35:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 15:35:49 volumio volumio[1317]: info: Executing endpoint qc_getconfig Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 26 15:35:49 volumio qobuz-connect[3126]: 20251226 15:35:49.320 [3126.3126] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 26 15:35:49 volumio volumio[1317]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 26 15:35:49 volumio volumio[1317]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 26 15:35:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 15:35:49 volumio sudo[3107]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio volumio[1317]: info: Shairport-Sync Started Dec 26 15:35:49 volumio qobuz-connect[3126]: 20251226 15:35:49.347 [3126.3126] INFO VolumeManager: [0x193e428]: Setting new playback volume: 75 Dec 26 15:35:49 volumio qobuz-connect[3126]: 20251226 15:35:49.347 [3126.3126] INFO VolumeManager: [0x193e428]: Setting new mute state: 0 Dec 26 15:35:49 volumio qobuz-connect[3126]: 20251226 15:35:49.347 [3126.3126] INFO QobuzConnect: [0x193edf8]: Client initialized! Dec 26 15:35:49 volumio qobuz-connect[3126]: 20251226 15:35:49.347 [3126.3126] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 26 15:35:49 volumio qobuz-connect[3126]: 20251226 15:35:49.358 [3126.3126] INFO LocalConfigManager: [0x193dd08]: Starting Local Configuration server Dec 26 15:35:49 volumio qobuz-connect[3126]: 20251226 15:35:49.358 [3126.3126] INFO SampleApp: Starting Local configuration server Dec 26 15:35:49 volumio qobuz-connect[3126]: 20251226 15:35:49.359 [3126.3126] INFO SampleApp: Connected to UNIX socket client 0x1928818 Dec 26 15:35:49 volumio volumiobt[3058]: INFO:a2dp-agent:Connecting to system bus. Dec 26 15:35:49 volumio volumiobt[3058]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Dec 26 15:35:49 volumio volumiobt[3058]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Dec 26 15:35:49 volumio volumiobt[3058]: INFO:a2dp-agent:Registering agent. Dec 26 15:35:49 volumio volumiobt[3058]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Dec 26 15:35:49 volumio volumiobt[3058]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Dec 26 15:35:49 volumio qobuz-connect[3126]: 20251226 15:35:49.471 [3126.3126] INFO SampleApp: Playback volume changed: 75 Dec 26 15:35:49 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:49 volumio mpd[3113]: 2025-12-26T15:35:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 15:35:49 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 15:35:49 volumio sudo[3019]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio sudo[2952]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio sudo[3075]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:49 volumio volumio[1317]: error: MPD error: The expression evaluated to a falsy value: Dec 26 15:35:49 volumio volumio[1317]: assert.ok(self.idling) Dec 26 15:35:49 volumio volumio[1317]: error: The expression evaluated to a falsy value: Dec 26 15:35:49 volumio volumio[1317]: assert.ok(self.idling) Dec 26 15:35:49 volumio volumio[1317]: error: MPD error: The expression evaluated to a falsy value: Dec 26 15:35:49 volumio volumio[1317]: assert.ok(self.idling) Dec 26 15:35:49 volumio volumio[1317]: error: The expression evaluated to a falsy value: Dec 26 15:35:49 volumio volumio[1317]: assert.ok(self.idling) Dec 26 15:35:49 volumio volumio[1317]: error: updateQueue error: null Dec 26 15:35:51 volumio volumio[1317]: info: TidalConnect service stoped! Dec 26 15:35:51 volumio volumio[1317]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 26 15:35:51 volumio volumio[1317]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 26 15:35:51 volumio sudo[3159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 26 15:35:51 volumio sudo[3159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:51 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 26 15:35:51 volumio sudo[3159]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:51 volumio volumio[1317]: info: Executing endpoint tc_getconfig Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 26 15:35:51 volumio vtcs[3162]: STARTING TidalConnect services, version: 1.5.2.56 Dec 26 15:35:51 volumio vtcs[3162]: STARTED TidalConnect services. Dec 26 15:35:51 volumio volumio[1317]: info: Executing endpoint tc_connect Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 26 15:35:51 volumio volumio[1317]: info: Connecting to TidalConnect Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::servicePushState Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:51 volumio volumio[1317]: info: CorePlayQueue::getTrack 0 Dec 26 15:35:51 volumio volumio[1317]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 26 15:35:51 volumio volumio[1317]: verbose: CURRENT POSITION 0 Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::syncState stateService stop Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::syncState currentStatus stop Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:51 volumio volumio[1317]: info: No code Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::servicePushState Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:51 volumio volumio[1317]: info: CorePlayQueue::getTrack 0 Dec 26 15:35:51 volumio volumio[1317]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 26 15:35:51 volumio volumio[1317]: verbose: CURRENT POSITION 0 Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::syncState stateService stop Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::syncState currentStatus stop Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:51 volumio volumio[1317]: info: No code Dec 26 15:35:51 volumio volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:35:51 volumio volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:35:51 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:52 volumio sudo[3184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 26 15:35:52 volumio sudo[3184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:52 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 15:35:52 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 15:35:52 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 26 15:35:52 volumio sudo[3184]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:52 volumio volumio[1317]: info: Remote SSH Started Dec 26 15:35:52 volumio autossh[3187]: port set to 0, monitoring disabled Dec 26 15:35:52 volumio autossh[3187]: starting ssh (count 1) Dec 26 15:35:52 volumio autossh[3187]: ssh child pid is 3190 Dec 26 15:35:52 volumio volumio[1317]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 26 15:35:52 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:35:52 volumio volumio[1317]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NOHtInTOCRZ3gOMiYYOYW1gyO9D2.848542fcf88b9c3590c0b5922f95341a.state.status' Dec 26 15:35:52 volumio volumiossh-tunnel[3190]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 26 15:35:52 volumio sudo[3195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 15:35:52 volumio sudo[3195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:52 volumio sudo[3198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 15:35:52 volumio sudo[3198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:52 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 15:35:52 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 15:35:52 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 15:35:52 volumio sudo[3200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 15:35:52 volumio sudo[3200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:35:52 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 15:35:52 volumio mpd_monitor.sh[3202]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 15:35:52 volumio sudo[3195]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:52 volumio volumio[1317]: info: Successfully started MPD Monitor Dec 26 15:35:52 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 15:35:52 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 15:35:52 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 15:35:52 volumio sudo[3198]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:52 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 15:35:52 volumio mpd_monitor.sh[3207]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 15:35:52 volumio sudo[3200]: pam_unix(sudo:session): session closed for user root Dec 26 15:35:52 volumio volumio[1317]: info: Successfully started MPD Monitor Dec 26 15:35:52 volumio volumio[1317]: info: Successfully started MPD Monitor Dec 26 15:35:53 volumio volumio[1317]: info: MRS: Getting audio outputs on start Dec 26 15:35:53 volumio volumio[1317]: info: MRS: Requesting all other devices output Dec 26 15:35:54 volumio volumio[1317]: info: TidalConnect service started! Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 15:36:15 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 26 15:36:15 volumio volumio-remote-updater[967]: Test mode disabled Dec 26 15:36:15 volumio volumio-remote-updater[967]: Alpha mode disabled Dec 26 15:36:15 volumio volumio-remote-updater[967]: Alpha legacy test mode disabled Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 26 15:36:15 volumio volumio[1317]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 26 15:36:15 volumio volumio[1317]: info: System name has changed, restarting Shairport Sync Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:36:15 volumio volumio[1317]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 15:36:15 volumio volumio[1317]: info: Updating MyVolumio device info Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:36:15 volumio volumio[1317]: info: QobuzConnect: setDeactiveState invoked Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:36:15 volumio vtcs[3162]: [2025-12-26 15:36:15.379] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 26 15:36:15 volumio vtcs[3162]: [2025-12-26 15:36:15.381] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 26 15:36:15 volumio volumio[1317]: info: Discovery: Restarting Advertising due to device name change Dec 26 15:36:15 volumio sudo[3251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 26 15:36:15 volumio sudo[3251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:15 volumio sudo[3253]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 26 15:36:15 volumio sudo[3253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:15 volumio sudo[3256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 26 15:36:15 volumio sudo[3256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:15 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 26 15:36:15 volumio sudo[3253]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:15 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 26 15:36:15 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 26 15:36:15 volumio sudo[3264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 26 15:36:15 volumio sudo[3264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:15 volumio sudo[3251]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 15:36:15 volumio volumio[1317]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 15:36:15 volumio sudo[3256]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:15 volumio volumio[1317]: info: Starting Shairport Sync Dec 26 15:36:15 volumio sudo[3264]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:15 volumio sudo[3275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 15:36:15 volumio sudo[3273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 26 15:36:15 volumio sudo[3275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:15 volumio sudo[3273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:15 volumio sudo[3271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 26 15:36:15 volumio sudo[3271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:15 volumio sudo[3271]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:15 volumio volumio[1317]: info: Permissions for /etc/hosts set Dec 26 15:36:15 volumio volumio[1317]: info: Hostname now is volumiobureau Dec 26 15:36:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 15:36:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 15:36:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 15:36:15 volumio systemd[1]: shairport-sync.service: Consumed 1.537s CPU time. Dec 26 15:36:15 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 26 15:36:15 volumio qobuz-connect[3126]: 20251226 15:36:15.461 [3126.3126] INFO SampleApp: Stopping Local configuration server Dec 26 15:36:15 volumio sudo[3280]: volumio : unable to resolve host volumio: System error Dec 26 15:36:15 volumio sudo[3280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumiobureau Dec 26 15:36:15 volumio sudo[3280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:15 volumiobureau sudo[3280]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:15 volumiobureau sudo[3282]: volumio : unable to resolve host volumio: System error Dec 26 15:36:15 volumiobureau volumio[1317]: info: New hostname set Dec 26 15:36:15 volumiobureau sudo[3282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 26 15:36:15 volumiobureau sudo[3282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:15 volumiobureau systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 15:36:15 volumiobureau sudo[3275]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:15 volumiobureau sudo[3282]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:15 volumiobureau volumio[1317]: info: Shairport-Sync Started Dec 26 15:36:15 volumiobureau volumio[1317]: info: Permissions for /etc/avahi/services/volumio.service Dec 26 15:36:15 volumiobureau avahi-daemon[954]: Files changed, reloading. Dec 26 15:36:15 volumiobureau avahi-daemon[954]: Loading service file /services/volumio.service. Dec 26 15:36:15 volumiobureau volumio[1317]: info: Avahi name changed to volumiobureau Dec 26 15:36:15 volumiobureau volumio[1317]: info: Successfully Updated MyVolumio device Dec 26 15:36:16 volumiobureau avahi-daemon[954]: Service "VolumioBureau" (/services/volumio.service) successfully established. Dec 26 15:36:16 volumiobureau volumio[1317]: info: Discovery: A device disappeared from network Dec 26 15:36:16 volumiobureau volumio[1317]: info: Discovery: Device volumio disappeared from network Dec 26 15:36:16 volumiobureau volumio[1317]: info: Discovery: A device disappeared from network Dec 26 15:36:17 volumiobureau qobuz-connect[3126]: 20251226 15:36:17.388 [3126.3126] INFO SampleApp: shat down connection on UNIX socket Dec 26 15:36:17 volumiobureau volumio[1317]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 26 15:36:17 volumiobureau volumio[1317]: info: QobuzConnect: setDeactiveState invoked Dec 26 15:36:17 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:36:17 volumiobureau systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 26 15:36:17 volumiobureau systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 26 15:36:17 volumiobureau systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 26 15:36:17 volumiobureau sudo[3273]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:17 volumiobureau volumio[1317]: info: Executing endpoint qc_getconfig Dec 26 15:36:17 volumiobureau volumio[1317]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 26 15:36:17 volumiobureau qobuz-connect[3314]: 20251226 15:36:17.429 [3314.3314] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 26 15:36:17 volumiobureau qobuz-connect[3314]: 20251226 15:36:17.430 [3314.3314] INFO VolumeManager: [0x17b7428]: Setting new playback volume: 75 Dec 26 15:36:17 volumiobureau qobuz-connect[3314]: 20251226 15:36:17.430 [3314.3314] INFO VolumeManager: [0x17b7428]: Setting new mute state: 0 Dec 26 15:36:17 volumiobureau qobuz-connect[3314]: 20251226 15:36:17.430 [3314.3314] INFO QobuzConnect: [0x17b7df8]: Client initialized! Dec 26 15:36:17 volumiobureau qobuz-connect[3314]: 20251226 15:36:17.430 [3314.3314] INFO SampleApp: Starting Avahi advertising, name: VolumioBureau, service name: _qobuz-connect._tcp Dec 26 15:36:17 volumiobureau volumio[1317]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 26 15:36:17 volumiobureau volumio[1317]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 26 15:36:17 volumiobureau qobuz-connect[3314]: 20251226 15:36:17.434 [3314.3314] INFO LocalConfigManager: [0x17b6d08]: Starting Local Configuration server Dec 26 15:36:17 volumiobureau qobuz-connect[3314]: 20251226 15:36:17.434 [3314.3314] INFO SampleApp: Starting Local configuration server Dec 26 15:36:17 volumiobureau qobuz-connect[3314]: 20251226 15:36:17.434 [3314.3314] INFO SampleApp: Connected to UNIX socket client 0x17a1818 Dec 26 15:36:17 volumiobureau qobuz-connect[3314]: 20251226 15:36:17.578 [3314.3314] INFO SampleApp: Playback volume changed: 75 Dec 26 15:36:17 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:36:18 volumiobureau volumio[1317]: info: TidalConnect service stoped! Dec 26 15:36:18 volumiobureau volumio[1317]: info: TidalConnect service stoped! Dec 26 15:36:18 volumiobureau volumio[1317]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 26 15:36:18 volumiobureau volumio[1317]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 26 15:36:18 volumiobureau sudo[3329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 26 15:36:18 volumiobureau sudo[3329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 15:36:18 volumiobureau systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 26 15:36:18 volumiobureau sudo[3329]: pam_unix(sudo:session): session closed for user root Dec 26 15:36:18 volumiobureau volumio[1317]: info: Executing endpoint tc_getconfig Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 26 15:36:18 volumiobureau vtcs[3332]: STARTING TidalConnect services, version: 1.5.2.56 Dec 26 15:36:18 volumiobureau vtcs[3332]: STARTED TidalConnect services. Dec 26 15:36:18 volumiobureau volumio[1317]: info: Executing endpoint tc_connect Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 26 15:36:18 volumiobureau volumio[1317]: info: Connecting to TidalConnect Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::servicePushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CorePlayQueue::getTrack 0 Dec 26 15:36:18 volumiobureau volumio[1317]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 26 15:36:18 volumiobureau volumio[1317]: verbose: CURRENT POSITION 0 Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::syncState stateService stop Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::syncState currentStatus stop Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:36:18 volumiobureau volumio[1317]: info: No code Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::servicePushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CorePlayQueue::getTrack 0 Dec 26 15:36:18 volumiobureau volumio[1317]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 26 15:36:18 volumiobureau volumio[1317]: verbose: CURRENT POSITION 0 Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::syncState stateService stop Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::syncState currentStatus stop Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:36:18 volumiobureau volumio[1317]: info: No code Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreStateMachine::pushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioPushState Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output update for this device Dec 26 15:36:18 volumiobureau volumio[1317]: info: MRS: Pushing multiroomSync output Dec 26 15:36:18 volumiobureau volumio[1317]: info: CoreCommandRouter::volumioGetState Dec 26 15:36:18 volumiobureau volumio[1317]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NOHtInTOCRZ3gOMiYYOYW1gyO9D2.848542fcf88b9c3590c0b5922f95341a.state.status' Dec 26 15:36:18 volumiobureau volumio[1317]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NOHtInTOCRZ3gOMiYYOYW1gyO9D2.848542fcf88b9c3590c0b5922f95341a.state.status' Dec 26 15:36:18 volumiobureau volumio[1317]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NOHtInTOCRZ3gOMiYYOYW1gyO9D2.848542fcf88b9c3590c0b5922f95341a.state.status' Dec 26 15:36:18 volumiobureau volumio[1317]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NOHtInTOCRZ3gOMiYYOYW1gyO9D2.848542fcf88b9c3590c0b5922f95341a.state.status' Dec 26 15:36:18 volumiobureau volumio[1317]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NOHtInTOCRZ3gOMiYYOYW1gyO9D2.848542fcf88b9c3590c0b5922f95341a.state.status' Dec 26 15:36:18 volumiobureau volumio[1317]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NOHtInTOCRZ3gOMiYYOYW1gyO9D2.848542fcf88b9c3590c0b5922f95341a.state.status' Dec 26 15:36:20 volumiobureau volumio[1317]: info: Discovery: Started advertising with name: VolumioBureau Dec 26 15:36:21 volumiobureau volumio[1317]: compat.c: read() failed: Resource temporarily unavailable Dec 26 15:36:21 volumiobureau volumio[1317]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 15:36:21 volumiobureau volumio[1317]: Error: dns service error: unknown Dec 26 15:36:21 volumiobureau volumio[1317]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 26 15:36:21 volumiobureau volumio[1317]: errorCode: -65537 Dec 26 15:36:21 volumiobureau volumio[1317]: } Dec 26 15:36:21 volumiobureau volumio[1317]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 15:36:21 volumiobureau sudo[3364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 15:35' Dec 26 15:36:21 volumiobureau sudo[3364]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"