Jan 12 23:15:01 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 23:15:03 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 23:15:03 volumiotowers volumio[1160]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 12 23:15:03 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 12 23:15:03 volumiotowers volumio[1160]: info: Received Get System Version Jan 12 23:15:03 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 23:15:03 volumiotowers volumio[1160]: info: Received Get System Info Jan 12 23:15:03 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 23:15:03 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 23:15:03 volumiotowers volumio[1160]: info: Discovery: Getting this device information Jan 12 23:15:03 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:03 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:03 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 23:15:21 volumiotowers upmpdcli[1807]: writing RSA key Jan 12 23:15:23 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 12 23:15:23 volumiotowers volumio[1160]: info: MyVolumio login type: Token Jan 12 23:15:25 volumiotowers volumio[1160]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 12 23:15:26 volumiotowers volumio[1160]: info: MyVolumio token set successfully Jan 12 23:15:26 volumiotowers volumio[1160]: info: MYVOLUMIO: Adding device Jan 12 23:15:26 volumiotowers volumio[1160]: info: MYVOLUMIO: Evaluating Server Jan 12 23:15:27 volumiotowers volumio[1160]: info: MyVolumio Plan changed: premium Jan 12 23:15:27 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 12 23:15:27 volumiotowers volumio[1160]: info: Removing browser output: myVolumio user plan is not superstar Jan 12 23:15:27 volumiotowers volumio[1160]: info: Removing audio output: Jan 12 23:15:27 volumiotowers volumio[1160]: info: MYVOLUMIO: Adding device Jan 12 23:15:27 volumiotowers volumio[1160]: info: MYVOLUMIO: Evaluating Server Jan 12 23:15:28 volumiotowers volumio[1160]: info: Remote config written successfully Jan 12 23:15:28 volumiotowers volumio[1160]: info: Starting Tunnel 1 Jan 12 23:15:28 volumiotowers volumio[1160]: info: Starting Tunnel Connection Checker Jan 12 23:15:28 volumiotowers volumio[1160]: info: MYVolumio Device disabled Jan 12 23:15:28 volumiotowers volumio[1160]: info: MyVolumio status changed Jan 12 23:15:28 volumiotowers volumio[1160]: info: Streaming services startup Jan 12 23:15:28 volumiotowers volumio[1160]: info: Starting Streaming Daemon Jan 12 23:15:28 volumiotowers sudo[1856]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:28 volumiotowers sudo[1856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 23:15:28 volumiotowers sudo[1856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:28 volumiotowers sudo[1856]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:28 volumiotowers volumio[1160]: error: Cannot start Volumio Streaming Daemon Jan 12 23:15:28 volumiotowers volumio[1160]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 23:15:28 volumiotowers volumio[1160]: sudo: unable to resolve host volumiotowers: System error Jan 12 23:15:28 volumiotowers volumio[1160]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 23:15:32 volumiotowers sudo[1872]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:32 volumiotowers sudo[1872]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 23:15:32 volumiotowers sudo[1874]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:32 volumiotowers sudo[1872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:32 volumiotowers sudo[1874]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 23:15:32 volumiotowers sudo[1874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:32 volumiotowers sudo[1872]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:32 volumiotowers sudo[1874]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:32 volumiotowers volumio[1160]: verbose: New Socket.io Connection to 192.168.88.127 from 192.168.88.120 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 12 23:15:32 volumiotowers volumio[1160]: info: Setting Geolocation for MyVolumio to eu4 Jan 12 23:15:32 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:32 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:32 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:32 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:32 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:33 volumiotowers volumio[1160]: info: Received Get System Info Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 23:15:33 volumiotowers volumio[1160]: info: Discovery: Getting this device information Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:33 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:33 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:33 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:33 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 23:15:33 volumiotowers volumio[1160]: info: Received Get System Info Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 23:15:33 volumiotowers volumio[1160]: info: Discovery: Getting this device information Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:33 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:33 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:33 volumiotowers volumio[1160]: info: Listing playlists Jan 12 23:15:33 volumiotowers volumio[1160]: info: Received Get System Info Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 23:15:33 volumiotowers volumio[1160]: info: Discovery: Getting this device information Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:33 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:33 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:33 volumiotowers volumio[1160]: info: Updating MyVolumio device info Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 12 23:15:33 volumiotowers volumio[1160]: info: Setting Geolocation for MyVolumio to eu4 Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:33 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:34 volumiotowers volumio[1160]: info: Successfully Added MyVolumio device Jan 12 23:15:34 volumiotowers volumio[1160]: info: MYVolumio Device enabled Jan 12 23:15:34 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 12 23:15:34 volumiotowers volumio[1160]: info: MyVolumio status changed Jan 12 23:15:34 volumiotowers volumio[1160]: info: Streaming services startup Jan 12 23:15:34 volumiotowers volumio[1160]: info: Starting Streaming Daemon Jan 12 23:15:34 volumiotowers sudo[1879]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:34 volumiotowers sudo[1879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 23:15:34 volumiotowers sudo[1879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:34 volumiotowers sudo[1879]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:34 volumiotowers volumio[1160]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Jan 12 23:15:35 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:35 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:15:35 volumiotowers volumio[1160]: info: Updating MyVolumio device info Jan 12 23:15:35 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:35 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:35 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:35 volumiotowers volumio[1160]: error: Cannot start Volumio Streaming Daemon Jan 12 23:15:35 volumiotowers volumio[1160]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 23:15:35 volumiotowers volumio[1160]: sudo: unable to resolve host volumiotowers: System error Jan 12 23:15:35 volumiotowers volumio[1160]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 23:15:36 volumiotowers sudo[1887]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:36 volumiotowers sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 12 23:15:36 volumiotowers sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:36 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 12 23:15:36 volumiotowers 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. Jan 12 23:15:36 volumiotowers 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. Jan 12 23:15:36 volumiotowers systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 23:15:36 volumiotowers sudo[1887]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:36 volumiotowers autossh[1890]: port set to 0, monitoring disabled Jan 12 23:15:36 volumiotowers autossh[1890]: starting ssh (count 1) Jan 12 23:15:36 volumiotowers autossh[1890]: ssh child pid is 1893 Jan 12 23:15:37 volumiotowers volumiossh-tunnel[1893]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Jan 12 23:15:38 volumiotowers volumio[1160]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jan 12 23:15:38 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 12 23:15:44 volumiotowers sudo[1500]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:46 volumiotowers sudo[1913]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:46 volumiotowers sudo[1913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 12 23:15:46 volumiotowers sudo[1913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:46 volumiotowers sudo[1913]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:46 volumiotowers volumio[1160]: sudo: unable to resolve host volumiotowers: System error Jan 12 23:15:46 volumiotowers volumio[1160]: info: MRS: MultiRoom plugin initialized Jan 12 23:15:46 volumiotowers volumio[1160]: info: MRS: STOPPING SNAPCLIENT Jan 12 23:15:46 volumiotowers sudo[1931]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:46 volumiotowers volumio[1160]: info: MRS: Snap server stop Jan 12 23:15:46 volumiotowers sudo[1931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 12 23:15:46 volumiotowers sudo[1931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:46 volumiotowers volumio[1160]: info: MRS: STOPPING volumioStreaming Jan 12 23:15:46 volumiotowers sudo[1934]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:47 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 12 23:15:47 volumiotowers sudo[1931]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:47 volumiotowers sudo[1934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 12 23:15:47 volumiotowers sudo[1934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:47 volumiotowers sudo[1936]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:47 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 12 23:15:47 volumiotowers sudo[1936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 12 23:15:47 volumiotowers sudo[1936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:47 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 12 23:15:47 volumiotowers sudo[1938]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:47 volumiotowers sudo[1938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 12 23:15:47 volumiotowers sudo[1934]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:47 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 12 23:15:47 volumiotowers sudo[1938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:47 volumiotowers sudo[1938]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:47 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 12 23:15:47 volumiotowers sudo[1936]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:47 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 12 23:15:47 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 12 23:15:47 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 12 23:15:47 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 12 23:15:47 volumiotowers volumio[1160]: info: Preparing to generate the ALSA configuration file Jan 12 23:15:47 volumiotowers volumio[1160]: info: Successfully Updated MyVolumio device Jan 12 23:15:47 volumiotowers volumio[1160]: info: Successfully Updated MyVolumio device Jan 12 23:15:47 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 12 23:15:47 volumiotowers volumio[1160]: info: Successfully Added MyVolumio device Jan 12 23:15:47 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 23:15:47 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 23:15:47 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 12 23:15:47 volumiotowers volumio[1160]: info: MRS: Removed streaming files Jan 12 23:15:47 volumiotowers volumio[1160]: info: MRS: volumioStreaming STOPPED Jan 12 23:15:47 volumiotowers volumio[1160]: info: MRS: SNAPSERVER STOPPED Jan 12 23:15:47 volumiotowers volumio[1160]: info: MRS: SNAPCLIENT STOPPED Jan 12 23:15:47 volumiotowers volumio[1160]: info: Remote SSH Started Jan 12 23:15:48 volumiotowers volumio[1160]: info: Asound.conf file unchanged, so no further update is needed Jan 12 23:15:48 volumiotowers volumio[1160]: info: Output device has changed, restarting MPD Jan 12 23:15:48 volumiotowers volumio[1160]: info: Output device has changed, restarting Shairport Sync Jan 12 23:15:48 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:48 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:48 volumiotowers sudo[1945]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:49 volumiotowers sudo[1947]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:49 volumiotowers sudo[1945]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 23:15:49 volumiotowers sudo[1945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:49 volumiotowers sudo[1945]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:49 volumiotowers sudo[1947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 23:15:49 volumiotowers sudo[1947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:49 volumiotowers volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 23:15:49 volumiotowers volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 23:15:49 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:49 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 12 23:15:49 volumiotowers volumio[1160]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jan 12 23:15:49 volumiotowers systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 12 23:15:49 volumiotowers volumio[1160]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jan 12 23:15:49 volumiotowers volumio[1160]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jan 12 23:15:49 volumiotowers volumio[1160]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Jan 12 23:15:49 volumiotowers volumio[1160]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jan 12 23:15:49 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 12 23:15:49 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 12 23:15:49 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output for this device Jan 12 23:15:49 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output Jan 12 23:15:49 volumiotowers volumio[1160]: info: Adding audio output: Jan 12 23:15:49 volumiotowers volumio[1160]: info: Adding audio output: Jan 12 23:15:49 volumiotowers volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 23:15:49 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:49 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 12 23:15:49 volumiotowers volumio[1160]: info: Adding METAVOLUMIO REST API Endpoints Jan 12 23:15:49 volumiotowers volumio[1160]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 12 23:15:49 volumiotowers volumio[1160]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 12 23:15:49 volumiotowers volumio[1160]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 12 23:15:49 volumiotowers volumio[1160]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 12 23:15:49 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 12 23:15:49 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 12 23:15:49 volumiotowers volumio[1160]: info: Preparing CD Folders Jan 12 23:15:49 volumiotowers volumio[1160]: info: Adding CD REST API Endpoints Jan 12 23:15:49 volumiotowers volumio[1160]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 12 23:15:49 volumiotowers volumio[1160]: info: Starting UDEV Watcher for CD Jan 12 23:15:49 volumiotowers volumio[1160]: info: Detecting CD presence with UDEV Jan 12 23:15:49 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 12 23:15:49 volumiotowers bluetoothd[896]: Path / reserved for Adv Monitor app :1.22 Jan 12 23:15:49 volumiotowers bluetoothd[896]: Adv Monitor app :1.22 disconnected from D-Bus Jan 12 23:15:50 volumiotowers systemd[1]: mpd.service: Deactivated successfully. Jan 12 23:15:50 volumiotowers systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 12 23:15:50 volumiotowers systemd[1]: mpd.service: Consumed 5.702s CPU time. Jan 12 23:15:50 volumiotowers systemd[1]: mpd.socket: Deactivated successfully. Jan 12 23:15:50 volumiotowers systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 12 23:15:50 volumiotowers systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 12 23:15:50 volumiotowers systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 12 23:15:50 volumiotowers systemd[1]: Starting mpd.service - Music Player Daemon... Jan 12 23:15:51 volumiotowers sudo[1979]: root : unable to resolve host volumiotowers: System error Jan 12 23:15:51 volumiotowers sudo[1979]: sudo: unable to resolve host volumiotowers: System error Jan 12 23:15:51 volumiotowers sudo[1979]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 23:15:51 volumiotowers sudo[1979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 12 23:15:51 volumiotowers sudo[1979]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:54 volumiotowers volumio[1160]: warn: [cd-plugin] cdspeedctl: device or media not ready Jan 12 23:15:54 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 12 23:15:54 volumiotowers volumio[1160]: info: Adding inputs REST Endpoints Jan 12 23:15:54 volumiotowers volumio[1160]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 12 23:15:54 volumiotowers volumio[1160]: info: Scanning Audio Inputs Jan 12 23:15:54 volumiotowers volumio[1160]: info: Checking against Known Cards name Jan 12 23:15:54 volumiotowers volumio[1160]: info: Adding Server instance for streaming Jan 12 23:15:54 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 12 23:15:54 volumiotowers volumio[1160]: error: Hi Res Audio Failed Login: Missing Login Data Jan 12 23:15:54 volumiotowers volumio[1160]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 12 23:15:54 volumiotowers volumio[1160]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 12 23:15:54 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 12 23:15:54 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 12 23:15:54 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 12 23:15:54 volumiotowers volumio[1160]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 12 23:15:54 volumiotowers volumio[1160]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 12 23:15:54 volumiotowers volumio[1160]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 12 23:15:54 volumiotowers sudo[1988]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:54 volumiotowers sudo[1988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 12 23:15:54 volumiotowers sudo[1988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:55 volumiotowers sudo[1995]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:55 volumiotowers volumio[1160]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 12 23:15:55 volumiotowers volumio[1160]: info: Adding TIDAL REST API Endpoints Jan 12 23:15:55 volumiotowers volumio[1160]: info: Stopping AccessToken refresher cron for QOBUZ Jan 12 23:15:55 volumiotowers sudo[1995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 12 23:15:55 volumiotowers sudo[1995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:55 volumiotowers sudo[1988]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:55 volumiotowers sudo[1995]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:55 volumiotowers sudo[1998]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:55 volumiotowers sudo[1998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 12 23:15:55 volumiotowers sudo[1998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:55 volumiotowers volumio[1160]: info: AccessToken refresher cron started for QOBUZ Jan 12 23:15:55 volumiotowers volumio[1160]: info: Adding QOBUZ REST API Endpoints Jan 12 23:15:55 volumiotowers volumio[1160]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 12 23:15:55 volumiotowers volumio[1160]: info: MRS: Getting audio outputs on start Jan 12 23:15:55 volumiotowers volumio[1160]: info: MRS: Requesting all other devices output Jan 12 23:15:55 volumiotowers systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 12 23:15:55 volumiotowers sudo[1998]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:55 volumiotowers volumio[1160]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Jan 12 23:15:55 volumiotowers volumio[1160]: info: MPD Permissions set Jan 12 23:15:55 volumiotowers sudo[2002]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:55 volumiotowers sudo[2002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 12 23:15:55 volumiotowers sudo[2002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:55 volumiotowers systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Jan 12 23:15:55 volumiotowers sudo[2002]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:55 volumiotowers volumiobt[2006]: INFO [BTSTART] Ensuring Bluetooth directory exists... Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:55 volumiotowers volumiobt[2007]: sudo: unable to resolve host volumiotowers: System error Jan 12 23:15:55 volumiotowers sudo[2007]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:55 volumiotowers sudo[2007]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Jan 12 23:15:55 volumiotowers sudo[2007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:55 volumiotowers sudo[2007]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:55 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:55 volumiotowers sudo[2011]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:55 volumiotowers volumiobt[2011]: sudo: unable to resolve host volumiotowers: System error Jan 12 23:15:56 volumiotowers sudo[2011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Jan 12 23:15:56 volumiotowers sudo[2011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:56 volumiotowers sudo[2011]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:56 volumiotowers volumiobt[2016]: INFO [BTSTART] Powering on Bluetooth if needed... Jan 12 23:15:56 volumiotowers volumio[1160]: info: Executing endpoint qc_getconfig Jan 12 23:15:56 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 12 23:15:56 volumiotowers bluetoothd[896]: Adv Monitor app :1.23 disconnected from D-Bus Jan 12 23:15:56 volumiotowers volumiobt[2022]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Jan 12 23:15:56 volumiotowers volumio[1160]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jan 12 23:15:56 volumiotowers qobuz-connect[2000]: 20260112 23:15:56.368 [2000.2000] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 12 23:15:56 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioStop Jan 12 23:15:56 volumiotowers volumio[1160]: info: CoreStateMachine::stop Jan 12 23:15:56 volumiotowers volumio[1160]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:15:56 volumiotowers volumio[1160]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Jan 12 23:15:56 volumiotowers volumio[1160]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:15:56 volumiotowers volumio[1160]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 12 23:15:56 volumiotowers volumio[1160]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 12 23:15:56 volumiotowers qobuz-connect[2000]: 20260112 23:15:56.801 [2000.2000] INFO VolumeManager: [0x916268]: Setting new playback volume: 75 Jan 12 23:15:56 volumiotowers qobuz-connect[2000]: 20260112 23:15:56.804 [2000.2000] INFO VolumeManager: [0x916268]: Setting new mute state: 0 Jan 12 23:15:56 volumiotowers qobuz-connect[2000]: 20260112 23:15:56.804 [2000.2000] INFO QobuzConnect: [0x916c38]: Client initialized! Jan 12 23:15:56 volumiotowers qobuz-connect[2000]: 20260112 23:15:56.804 [2000.2000] INFO SampleApp: Starting Avahi advertising, name: Volumio_TOWERS, service name: _qobuz-connect._tcp Jan 12 23:15:56 volumiotowers volumiobt[2023]: [176B blob data] Jan 12 23:15:56 volumiotowers volumiobt[2023]: [157B blob data] Jan 12 23:15:56 volumiotowers volumiobt[2023]: [157B blob data] Jan 12 23:15:56 volumiotowers volumiobt[2023]: [157B blob data] Jan 12 23:15:56 volumiotowers volumiobt[2023]: [113B blob data] Jan 12 23:15:56 volumiotowers volumiobt[2023]: [bluetoothctl]> discoverable on Jan 12 23:15:56 volumiotowers volumiobt[2023]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jan 12 23:15:56 volumiotowers volumiobt[2023]: [bluetoothctl]> pairable on Jan 12 23:15:56 volumiotowers volumio[1160]: info: Starting Shairport Sync Jan 12 23:15:56 volumiotowers bluetoothd[896]: Path / reserved for Adv Monitor app :1.24 Jan 12 23:15:57 volumiotowers sudo[2026]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:57 volumiotowers bluetoothd[896]: Adv Monitor app :1.24 disconnected from D-Bus Jan 12 23:15:57 volumiotowers qobuz-connect[2000]: 20260112 23:15:57.043 [2000.2000] INFO LocalConfigManager: [0x915b48]: Starting Local Configuration server Jan 12 23:15:57 volumiotowers qobuz-connect[2000]: 20260112 23:15:57.043 [2000.2000] INFO SampleApp: Starting Local configuration server Jan 12 23:15:57 volumiotowers qobuz-connect[2000]: 20260112 23:15:57.043 [2000.2000] INFO SampleApp: Playback volume changed: 75 Jan 12 23:15:57 volumiotowers qobuz-connect[2000]: 20260112 23:15:57.043 [2000.2000] INFO SampleApp: Connected to UNIX socket client 0x900818 Jan 12 23:15:57 volumiotowers volumio[1160]: info: Preparing to generate the ALSA configuration file Jan 12 23:15:57 volumiotowers volumiobt[2023]: [bluetoothctl]> Jan 12 23:15:57 volumiotowers volumiobt[2027]: INFO [BTSTART] Registering Bluetooth agent... Jan 12 23:15:57 volumiotowers sudo[2026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 23:15:57 volumiotowers sudo[2026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:57 volumiotowers systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 12 23:15:57 volumiotowers systemd[1]: shairport-sync.service: Deactivated successfully. Jan 12 23:15:57 volumiotowers systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 12 23:15:57 volumiotowers systemd[1]: shairport-sync.service: Consumed 1.232s CPU time. Jan 12 23:15:57 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:57 volumiotowers systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 12 23:15:57 volumiotowers sudo[2026]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:57 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:57 volumiotowers volumiobt[2030]: [NEW] Media /org/bluez/hci0 Jan 12 23:15:57 volumiotowers volumiobt[2030]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 12 23:15:57 volumiotowers volumiobt[2030]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 12 23:15:57 volumiotowers volumiobt[2030]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jan 12 23:15:57 volumiotowers volumio[1160]: info: Shairport-Sync Started Jan 12 23:15:57 volumiotowers volumio[1160]: info: Asound.conf file unchanged, so no further update is needed Jan 12 23:15:57 volumiotowers volumio[1160]: info: Output device has changed, restarting MPD Jan 12 23:15:57 volumiotowers volumio[1160]: info: Output device has changed, restarting Shairport Sync Jan 12 23:15:57 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:57 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:57 volumiotowers bluetoothd[896]: Adv Monitor app :1.26 disconnected from D-Bus Jan 12 23:15:57 volumiotowers sudo[2034]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:57 volumiotowers sudo[2034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 23:15:57 volumiotowers sudo[2034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:57 volumiotowers sudo[2034]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:57 volumiotowers sudo[2037]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:57 volumiotowers volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 23:15:57 volumiotowers volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 23:15:57 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:57 volumiotowers sudo[2037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 23:15:57 volumiotowers sudo[2037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:57 volumiotowers volumio[1160]: info: QobuzConnect: setDeactiveState invoked Jan 12 23:15:57 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:57 volumiotowers volumio[1160]: info: Preparing to generate the ALSA configuration file Jan 12 23:15:57 volumiotowers volumiobt[2036]: No agent is registered Jan 12 23:15:57 volumiotowers volumiobt[2036]: [NEW] Media /org/bluez/hci0 Jan 12 23:15:57 volumiotowers volumiobt[2036]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 12 23:15:57 volumiotowers volumiobt[2036]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 12 23:15:57 volumiotowers volumiobt[2036]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jan 12 23:15:57 volumiotowers systemd[1]: mpd.service: Deactivated successfully. Jan 12 23:15:57 volumiotowers systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 12 23:15:57 volumiotowers systemd[1]: mpd.service: Consumed 1.681s CPU time. Jan 12 23:15:57 volumiotowers systemd[1]: mpd.socket: Deactivated successfully. Jan 12 23:15:57 volumiotowers systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 12 23:15:57 volumiotowers systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 12 23:15:57 volumiotowers mpd_monitor.sh[1983]: MPD error: Connection reset by peer Jan 12 23:15:57 volumiotowers mpd_monitor.sh[1554]: MPD Monitor Service: MPD Appears to be inactive, restarting Jan 12 23:15:57 volumiotowers bluetoothd[896]: Adv Monitor app :1.27 disconnected from D-Bus Jan 12 23:15:57 volumiotowers volumiobt[2065]: INFO [BTSTART] Agent registered successfully. Jan 12 23:15:57 volumiotowers mpd_monitor.sh[2068]: mpd: no process found Jan 12 23:15:57 volumiotowers systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 12 23:15:57 volumiotowers systemd[1]: Starting mpd.service - Music Player Daemon... Jan 12 23:15:57 volumiotowers volumiobt[2070]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Jan 12 23:15:58 volumiotowers systemd[1]: mpd.service: Deactivated successfully. Jan 12 23:15:58 volumiotowers systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 12 23:15:58 volumiotowers systemd[1]: mpd.socket: Deactivated successfully. Jan 12 23:15:58 volumiotowers volumio[1160]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 12 23:15:58 volumiotowers volumio[1160]: info: Reading ALSA contributions from plugins. Jan 12 23:15:58 volumiotowers sudo[2067]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:58 volumiotowers systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 12 23:15:58 volumiotowers systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 12 23:15:58 volumiotowers systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 12 23:15:58 volumiotowers systemd[1]: Starting mpd.service - Music Player Daemon... Jan 12 23:15:58 volumiotowers volumio[1160]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ihx9WPQLYJRoVLLc7fIvei6zc6s1.86c0d4839d7a3f8a0dd9d6ad0e5fdbee.state.status' Jan 12 23:15:58 volumiotowers sudo[2067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 12 23:15:58 volumiotowers sudo[2067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:58 volumiotowers volumio[1160]: info: MPD Permissions set Jan 12 23:15:58 volumiotowers volumio[1160]: error: Unable to stop TidalConnect service: Jan 12 23:15:58 volumiotowers sudo[2067]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:58 volumiotowers volumio[1160]: info: TidalConnect service stoped! Jan 12 23:15:58 volumiotowers sudo[2076]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:58 volumiotowers sudo[2076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 12 23:15:58 volumiotowers sudo[2076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:58 volumiotowers systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 12 23:15:58 volumiotowers qobuz-connect[2000]: 20260112 23:15:58.339 [2000.2000] INFO SampleApp: Stopping Local configuration server Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:58 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:58 volumiotowers volumio[1160]: info: Starting Shairport Sync Jan 12 23:15:58 volumiotowers volumio[1160]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 12 23:15:58 volumiotowers volumio[1160]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 12 23:15:58 volumiotowers volumio[1160]: info: Asound.conf file written Jan 12 23:15:58 volumiotowers sudo[2092]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:58 volumiotowers sudo[2092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 23:15:58 volumiotowers sudo[2092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:58 volumiotowers sudo[2095]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:58 volumiotowers sudo[2097]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:58 volumiotowers sudo[2095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 12 23:15:58 volumiotowers sudo[2095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:58 volumiotowers sudo[2097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 12 23:15:58 volumiotowers sudo[2097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:58 volumiotowers systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 12 23:15:58 volumiotowers sudo[2097]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:58 volumiotowers volumio[1160]: sudo: unable to resolve host volumiotowers: System error Jan 12 23:15:58 volumiotowers systemd[1]: shairport-sync.service: Deactivated successfully. Jan 12 23:15:58 volumiotowers systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 12 23:15:58 volumiotowers volumio[1160]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 12 23:15:58 volumiotowers volumio[1160]: info: Output device has changed, restarting MPD Jan 12 23:15:58 volumiotowers systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 12 23:15:58 volumiotowers systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 12 23:15:58 volumiotowers sudo[2092]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:58 volumiotowers sudo[2095]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:59 volumiotowers volumio[1160]: info: Output device has changed, restarting Shairport Sync Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:59 volumiotowers qobuz-connect[2000]: 20260112 23:15:59.061 [2000.2000] INFO SampleApp: shat down connection on UNIX socket Jan 12 23:15:59 volumiotowers systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 12 23:15:59 volumiotowers systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 12 23:15:59 volumiotowers sudo[2111]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:59 volumiotowers sudo[2111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 23:15:59 volumiotowers sudo[2111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:15:59 volumiotowers systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 12 23:15:59 volumiotowers sudo[2076]: pam_unix(sudo:session): session closed for user root Jan 12 23:15:59 volumiotowers systemd[1]: mpd.service: Deactivated successfully. Jan 12 23:15:59 volumiotowers systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 12 23:15:59 volumiotowers systemd[1]: mpd.socket: Deactivated successfully. Jan 12 23:15:59 volumiotowers systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 12 23:15:59 volumiotowers systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 12 23:15:59 volumiotowers systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 12 23:15:59 volumiotowers systemd[1]: Starting mpd.service - Music Player Daemon... Jan 12 23:15:59 volumiotowers volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 23:15:59 volumiotowers volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: QobuzConnect: setDeactiveState invoked Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:59 volumiotowers volumio[1160]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Jan 12 23:15:59 volumiotowers volumio[1160]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 12 23:15:59 volumiotowers volumio[1160]: info: QobuzConnect: setDeactiveState invoked Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:59 volumiotowers volumio[1160]: info: Shairport-Sync Started Jan 12 23:15:59 volumiotowers volumio[1160]: info: Executing endpoint qc_getconfig Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 12 23:15:59 volumiotowers qobuz-connect[2115]: 20260112 23:15:59.712 [2115.2115] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers qobuz-connect[2115]: 20260112 23:15:59.749 [2115.2115] INFO VolumeManager: [0xe46268]: Setting new playback volume: 75 Jan 12 23:15:59 volumiotowers qobuz-connect[2115]: 20260112 23:15:59.749 [2115.2115] INFO VolumeManager: [0xe46268]: Setting new mute state: 0 Jan 12 23:15:59 volumiotowers qobuz-connect[2115]: 20260112 23:15:59.749 [2115.2115] INFO QobuzConnect: [0xe46c38]: Client initialized! Jan 12 23:15:59 volumiotowers qobuz-connect[2115]: 20260112 23:15:59.749 [2115.2115] INFO SampleApp: Starting Avahi advertising, name: Volumio_TOWERS, service name: _qobuz-connect._tcp Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 23:15:59 volumiotowers volumio[1160]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 12 23:15:59 volumiotowers volumio[1160]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 12 23:15:59 volumiotowers volumio[1160]: info: Starting Shairport Sync Jan 12 23:15:59 volumiotowers qobuz-connect[2115]: 20260112 23:15:59.917 [2115.2115] INFO LocalConfigManager: [0xe45b48]: Starting Local Configuration server Jan 12 23:15:59 volumiotowers qobuz-connect[2115]: 20260112 23:15:59.917 [2115.2115] INFO SampleApp: Starting Local configuration server Jan 12 23:15:59 volumiotowers qobuz-connect[2115]: 20260112 23:15:59.918 [2115.2115] INFO SampleApp: Connected to UNIX socket client 0xe30818 Jan 12 23:15:59 volumiotowers qobuz-connect[2115]: 20260112 23:15:59.918 [2115.2115] INFO SampleApp: Playback volume changed: 75 Jan 12 23:15:59 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:15:59 volumiotowers sudo[2168]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:15:59 volumiotowers sudo[2168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 23:15:59 volumiotowers sudo[2168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:16:00 volumiotowers systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 12 23:16:00 volumiotowers systemd[1]: shairport-sync.service: Deactivated successfully. Jan 12 23:16:00 volumiotowers systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 12 23:16:00 volumiotowers systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 12 23:16:00 volumiotowers sudo[2168]: pam_unix(sudo:session): session closed for user root Jan 12 23:16:00 volumiotowers volumio[1160]: info: Shairport-Sync Started Jan 12 23:16:00 volumiotowers sudo[2120]: root : unable to resolve host volumiotowers: System error Jan 12 23:16:00 volumiotowers sudo[2120]: sudo: unable to resolve host volumiotowers: System error Jan 12 23:16:00 volumiotowers sudo[2120]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 23:16:00 volumiotowers sudo[2120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 12 23:16:00 volumiotowers sudo[2120]: pam_unix(sudo:session): session closed for user root Jan 12 23:16:00 volumiotowers volumio[1160]: info: Executing endpoint tc_getconfig Jan 12 23:16:00 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 12 23:16:00 volumiotowers vtcs[2109]: STARTING TidalConnect services, version: 1.5.2.56 Jan 12 23:16:00 volumiotowers vtcs[2109]: STARTED TidalConnect services. Jan 12 23:16:00 volumiotowers volumio[1160]: info: Executing endpoint tc_connect Jan 12 23:16:00 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 12 23:16:00 volumiotowers volumio[1160]: info: Connecting to TidalConnect Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::servicePushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::pushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output update for this device Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:16:01 volumiotowers volumio[1160]: 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} Jan 12 23:16:01 volumiotowers volumio[1160]: verbose: CURRENT POSITION 0 Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::syncState stateService stop Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::syncState currentStatus stop Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::pushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output update for this device Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:16:01 volumiotowers volumio[1160]: info: No code Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::pushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output update for this device Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::servicePushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::pushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output update for this device Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CorePlayQueue::getTrack 0 Jan 12 23:16:01 volumiotowers volumio[1160]: 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} Jan 12 23:16:01 volumiotowers volumio[1160]: verbose: CURRENT POSITION 0 Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::syncState stateService stop Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::syncState currentStatus stop Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::pushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output update for this device Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:16:01 volumiotowers volumio[1160]: info: No code Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreStateMachine::pushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioPushState Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output update for this device Jan 12 23:16:01 volumiotowers volumio[1160]: info: MRS: Pushing multiroomSync output Jan 12 23:16:01 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:16:02 volumiotowers volumio[1160]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ihx9WPQLYJRoVLLc7fIvei6zc6s1.86c0d4839d7a3f8a0dd9d6ad0e5fdbee.state.status' Jan 12 23:16:02 volumiotowers volumio[1160]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ihx9WPQLYJRoVLLc7fIvei6zc6s1.86c0d4839d7a3f8a0dd9d6ad0e5fdbee.state.status' Jan 12 23:16:02 volumiotowers volumio[1160]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ihx9WPQLYJRoVLLc7fIvei6zc6s1.86c0d4839d7a3f8a0dd9d6ad0e5fdbee.state.status' Jan 12 23:16:02 volumiotowers volumio[1160]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.ihx9WPQLYJRoVLLc7fIvei6zc6s1.86c0d4839d7a3f8a0dd9d6ad0e5fdbee.state.status' Jan 12 23:16:02 volumiotowers volumio[1160]: error: Unable to start TidalConnect service: Jan 12 23:16:02 volumiotowers volumio[1160]: info: TidalConnect service started! Jan 12 23:16:02 volumiotowers volumiobt[2072]: INFO:a2dp-agent:Connecting to system bus. Jan 12 23:16:02 volumiotowers volumiobt[2072]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Jan 12 23:16:02 volumiotowers volumiobt[2072]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Jan 12 23:16:02 volumiotowers volumiobt[2072]: INFO:a2dp-agent:Registering agent. Jan 12 23:16:02 volumiotowers volumiobt[2072]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Jan 12 23:16:02 volumiotowers volumiobt[2072]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Jan 12 23:16:03 volumiotowers sudo[2106]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:16:04 volumiotowers sudo[2106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 23:16:04 volumiotowers sudo[2106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:16:04 volumiotowers sudo[2106]: pam_unix(sudo:session): session closed for user root Jan 12 23:16:04 volumiotowers volumio[1160]: info: MPD Permissions set Jan 12 23:16:04 volumiotowers sudo[2133]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:16:04 volumiotowers sudo[2133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 12 23:16:04 volumiotowers sudo[2133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:16:04 volumiotowers systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 12 23:16:04 volumiotowers sudo[2156]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:16:04 volumiotowers sudo[2156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 12 23:16:04 volumiotowers systemd[1]: vtcs.service: Deactivated successfully. Jan 12 23:16:04 volumiotowers systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 12 23:16:04 volumiotowers sudo[2156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:16:04 volumiotowers sudo[2133]: pam_unix(sudo:session): session closed for user root Jan 12 23:16:04 volumiotowers sudo[2156]: pam_unix(sudo:session): session closed for user root Jan 12 23:16:04 volumiotowers sudo[2207]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:16:04 volumiotowers sudo[2207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 12 23:16:04 volumiotowers sudo[2207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:16:04 volumiotowers systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 12 23:16:04 volumiotowers qobuz-connect[2115]: 20260112 23:16:04.962 [2115.2115] INFO SampleApp: Stopping Local configuration server Jan 12 23:16:05 volumiotowers qobuz-connect[2115]: 20260112 23:16:05.952 [2115.2115] INFO SampleApp: shat down connection on UNIX socket Jan 12 23:16:05 volumiotowers volumio[1160]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 12 23:16:05 volumiotowers volumio[1160]: info: QobuzConnect: setDeactiveState invoked Jan 12 23:16:05 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:16:05 volumiotowers systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 12 23:16:05 volumiotowers systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 12 23:16:06 volumiotowers systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 12 23:16:06 volumiotowers sudo[2207]: pam_unix(sudo:session): session closed for user root Jan 12 23:16:06 volumiotowers volumio[1160]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/bookworm/armhf Jan 12 23:16:06 volumiotowers volumio[1160]: info: Executing endpoint qc_getconfig Jan 12 23:16:06 volumiotowers volumio[1160]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 12 23:16:06 volumiotowers qobuz-connect[2209]: 20260112 23:16:06.293 [2209.2209] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 12 23:16:06 volumiotowers volumio[1160]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 12 23:16:06 volumiotowers volumio[1160]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 12 23:16:06 volumiotowers qobuz-connect[2209]: 20260112 23:16:06.304 [2209.2209] INFO VolumeManager: [0x3a3268]: Setting new playback volume: 75 Jan 12 23:16:06 volumiotowers qobuz-connect[2209]: 20260112 23:16:06.304 [2209.2209] INFO VolumeManager: [0x3a3268]: Setting new mute state: 0 Jan 12 23:16:06 volumiotowers qobuz-connect[2209]: 20260112 23:16:06.304 [2209.2209] INFO QobuzConnect: [0x3a3c38]: Client initialized! Jan 12 23:16:06 volumiotowers qobuz-connect[2209]: 20260112 23:16:06.304 [2209.2209] INFO SampleApp: Starting Avahi advertising, name: Volumio_TOWERS, service name: _qobuz-connect._tcp Jan 12 23:16:06 volumiotowers qobuz-connect[2209]: 20260112 23:16:06.401 [2209.2209] INFO LocalConfigManager: [0x3a2b48]: Starting Local Configuration server Jan 12 23:16:06 volumiotowers qobuz-connect[2209]: 20260112 23:16:06.401 [2209.2209] INFO SampleApp: Starting Local configuration server Jan 12 23:16:06 volumiotowers qobuz-connect[2209]: 20260112 23:16:06.402 [2209.2209] INFO SampleApp: Connected to UNIX socket client 0x38d818 Jan 12 23:16:06 volumiotowers qobuz-connect[2209]: 20260112 23:16:06.446 [2209.2209] INFO SampleApp: Playback volume changed: 75 Jan 12 23:16:06 volumiotowers volumio[1160]: info: CoreCommandRouter::volumioGetState Jan 12 23:16:07 volumiotowers volumio[1160]: error: Unable to stop TidalConnect service: Jan 12 23:16:07 volumiotowers volumio[1160]: info: TidalConnect service stoped! Jan 12 23:16:07 volumiotowers volumio[1160]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 12 23:16:07 volumiotowers volumio[1160]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 12 23:16:07 volumiotowers sudo[2226]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:16:08 volumiotowers sudo[2226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 12 23:16:08 volumiotowers sudo[2226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 23:16:08 volumiotowers systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 12 23:16:08 volumiotowers sudo[2226]: pam_unix(sudo:session): session closed for user root Jan 12 23:16:08 volumiotowers volumio[1160]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/bookworm/armhf Jan 12 23:16:08 volumiotowers volumio[1160]: info: Folder /tmp/plugins removed Jan 12 23:16:08 volumiotowers volumio[1160]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 23:16:08 volumiotowers volumio[1160]: Error: Unable to resolve or reject the same promise twice Jan 12 23:16:08 volumiotowers volumio[1160]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 12 23:16:08 volumiotowers volumio[1160]: at /volumio/app/pluginmanager.js:1087:11 Jan 12 23:16:08 volumiotowers volumio[1160]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jan 12 23:16:08 volumiotowers volumio[1160]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Jan 12 23:16:08 volumiotowers volumio[1160]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jan 12 23:16:08 volumiotowers volumio[1160]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:107:14 Jan 12 23:16:08 volumiotowers volumio[1160]: at FSReqCallback.oncomplete (node:fs:190:23) Jan 12 23:16:08 volumiotowers volumio[1160]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 23:16:12 volumiotowers sudo[2260]: volumio : unable to resolve host volumiotowers: System error Jan 12 23:16:12 volumiotowers sudo[2260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-12 23:15' Jan 12 23:16:12 volumiotowers sudo[2260]: 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"