Jan 28 18:25:14 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 28 18:25:14 volumio volumio[1193]: info: MyVolumio login type: Token Jan 28 18:25:15 volumio volumio[1193]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 28 18:25:16 volumio volumio[1193]: info: MyVolumio token set successfully Jan 28 18:25:16 volumio volumio[1193]: info: MYVOLUMIO: Adding device Jan 28 18:25:16 volumio volumio[1193]: info: MYVOLUMIO: Evaluating Server Jan 28 18:25:16 volumio volumio[1193]: info: MyVolumio Plan changed: premium Jan 28 18:25:16 volumio volumio[1193]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 28 18:25:16 volumio volumio[1193]: info: Removing browser output: myVolumio user plan is not superstar Jan 28 18:25:16 volumio volumio[1193]: info: Removing audio output: Jan 28 18:25:16 volumio volumio[1193]: info: MYVOLUMIO: Adding device Jan 28 18:25:16 volumio volumio[1193]: info: MYVOLUMIO: Evaluating Server Jan 28 18:25:16 volumio volumio[1193]: info: Remote config written successfully Jan 28 18:25:16 volumio volumio[1193]: info: Starting Tunnel 1 Jan 28 18:25:16 volumio volumio[1193]: info: Starting Tunnel Connection Checker Jan 28 18:25:17 volumio volumio[1193]: info: MYVolumio Device enabled Jan 28 18:25:17 volumio volumio[1193]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 28 18:25:17 volumio volumio[1193]: info: MyVolumio status changed Jan 28 18:25:17 volumio volumio[1193]: info: Streaming services startup Jan 28 18:25:17 volumio volumio[1193]: info: Starting Streaming Daemon Jan 28 18:25:17 volumio sudo[4496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 28 18:25:17 volumio sudo[4496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:17 volumio sudo[4496]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:17 volumio volumio[1193]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Jan 28 18:25:17 volumio volumio[1193]: error: Cannot start Volumio Streaming Daemon Jan 28 18:25:17 volumio volumio[1193]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 28 18:25:17 volumio volumio[1193]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 28 18:25:18 volumio volumio[1193]: info: Setting Geolocation for MyVolumio to eu8 Jan 28 18:25:18 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:18 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:18 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:19 volumio volumio[1193]: info: Updating MyVolumio device info Jan 28 18:25:19 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:19 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:19 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:21 volumio sudo[4540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 28 18:25:21 volumio sudo[4540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:21 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. Jan 28 18:25:21 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. Jan 28 18:25:21 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 18:25:21 volumio sudo[4540]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:21 volumio volumio[1193]: info: Remote SSH Started Jan 28 18:25:21 volumio autossh[4543]: port set to 0, monitoring disabled Jan 28 18:25:21 volumio autossh[4543]: starting ssh (count 1) Jan 28 18:25:21 volumio autossh[4543]: ssh child pid is 4546 Jan 28 18:25:21 volumio volumio[1193]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 28 18:25:22 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 28 18:25:22 volumio volumio[1193]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jan 28 18:25:22 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 28 18:25:22 volumio sudo[4551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 28 18:25:22 volumio sudo[4551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:22 volumio sudo[4551]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:22 volumio volumio[1193]: info: MRS: MultiRoom plugin initialized Jan 28 18:25:22 volumio volumio[1193]: info: MRS: STOPPING SNAPCLIENT Jan 28 18:25:22 volumio volumio[1193]: info: MRS: Snap server stop Jan 28 18:25:22 volumio volumio[1193]: info: MRS: STOPPING volumioStreaming Jan 28 18:25:22 volumio sudo[4568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 28 18:25:22 volumio sudo[4568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:22 volumio sudo[4570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 28 18:25:22 volumio sudo[4573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 28 18:25:22 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 28 18:25:22 volumio sudo[4573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:22 volumio sudo[4570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:22 volumio sudo[4575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 28 18:25:22 volumio sudo[4575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:22 volumio sudo[4575]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:22 volumio sudo[4568]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:22 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 28 18:25:22 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 28 18:25:22 volumio sudo[4570]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:22 volumio sudo[4573]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:22 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 28 18:25:22 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 28 18:25:22 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 28 18:25:22 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 28 18:25:23 volumio volumio[1193]: info: Preparing to generate the ALSA configuration file Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 28 18:25:23 volumio volumio[1193]: info: Setting Geolocation for MyVolumio to eu12 Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: Successfully Added MyVolumio device Jan 28 18:25:23 volumio volumio[1193]: info: MRS: Removed streaming files Jan 28 18:25:23 volumio volumio[1193]: info: MRS: volumioStreaming STOPPED Jan 28 18:25:23 volumio volumio[1193]: info: MRS: SNAPSERVER STOPPED Jan 28 18:25:23 volumio volumio[1193]: info: MRS: SNAPCLIENT STOPPED Jan 28 18:25:23 volumio volumio[1193]: info: Asound.conf file unchanged, so no further update is needed Jan 28 18:25:23 volumio volumio[1193]: info: Output device has changed, restarting MPD Jan 28 18:25:23 volumio volumio[1193]: info: Output device has changed, restarting Shairport Sync Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:23 volumio sudo[4582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 18:25:23 volumio sudo[4582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio sudo[4584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 18:25:23 volumio sudo[4584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio sudo[4582]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio volumio[1193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 18:25:23 volumio volumio[1193]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 28 18:25:23 volumio volumio[1193]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jan 28 18:25:23 volumio volumio[1193]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jan 28 18:25:23 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 18:25:23 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 18:25:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 18:25:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 18:25:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 18:25:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 18:25:23 volumio volumio[1193]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jan 28 18:25:23 volumio volumio[1193]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Jan 28 18:25:23 volumio volumio[1193]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jan 28 18:25:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 28 18:25:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 18:25:23 volumio volumio[1193]: info: MRS: Pushing multiroomSync output for this device Jan 28 18:25:23 volumio volumio[1193]: info: MRS: Pushing multiroomSync output Jan 28 18:25:23 volumio volumio[1193]: info: Adding audio output: Jan 28 18:25:23 volumio volumio[1193]: info: Adding audio output: Jan 28 18:25:23 volumio volumio[1193]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 28 18:25:23 volumio volumio[1193]: info: Adding METAVOLUMIO REST API Endpoints Jan 28 18:25:23 volumio volumio[1193]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 28 18:25:23 volumio volumio[1193]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 28 18:25:23 volumio volumio[1193]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 28 18:25:23 volumio volumio[1193]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 28 18:25:23 volumio volumio[1193]: info: Preparing CD Folders Jan 28 18:25:23 volumio volumio[1193]: info: Adding CD REST API Endpoints Jan 28 18:25:23 volumio volumio[1193]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 28 18:25:23 volumio volumio[1193]: info: Starting UDEV Watcher for CD Jan 28 18:25:23 volumio volumio[1193]: info: Detecting CD presence with UDEV Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 28 18:25:23 volumio sudo[4596]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 18:25:23 volumio sudo[4596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 18:25:23 volumio sudo[4596]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio volumio[1193]: info: [cd-plugin] Set CD speed to 1X Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 28 18:25:23 volumio volumio[1193]: info: Adding inputs REST Endpoints Jan 28 18:25:23 volumio volumio[1193]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 28 18:25:23 volumio volumio[1193]: info: Scanning Audio Inputs Jan 28 18:25:23 volumio volumio[1193]: info: Checking against Known Cards name Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 18:25:23 volumio volumio[1193]: info: [1769624723539] CoreMusicLibrary::Adding element HDA Intel MID Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 18:25:23 volumio volumio[1193]: Cannot find translation for source HDA Intel MID Jan 28 18:25:23 volumio volumio[1193]: info: Checking against Known Cards name Jan 28 18:25:23 volumio volumio[1193]: info: Adding Server instance for streaming Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 28 18:25:23 volumio volumio[1193]: error: Hi Res Audio Failed Login: Missing Login Data Jan 28 18:25:23 volumio volumio[1193]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 28 18:25:23 volumio volumio[1193]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 28 18:25:23 volumio volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 28 18:25:23 volumio volumio[1193]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 28 18:25:23 volumio sudo[4611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 28 18:25:23 volumio sudo[4611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 28 18:25:23 volumio sudo[4611]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio sudo[4618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 28 18:25:23 volumio sudo[4618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio volumio[1193]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 28 18:25:23 volumio volumio[1193]: info: Adding TIDAL REST API Endpoints Jan 28 18:25:23 volumio volumio[1193]: info: Stopping AccessToken refresher cron for QOBUZ Jan 28 18:25:23 volumio sudo[4618]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio sudo[4621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 28 18:25:23 volumio sudo[4621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio volumio[1193]: info: AccessToken refresher cron started for QOBUZ Jan 28 18:25:23 volumio volumio[1193]: info: Adding QOBUZ REST API Endpoints Jan 28 18:25:23 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 28 18:25:23 volumio sudo[4621]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio volumio[1193]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Jan 28 18:25:23 volumio volumio[1193]: info: MPD Permissions set Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:23 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Jan 28 18:25:23 volumio sudo[4625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 28 18:25:23 volumio sudo[4625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Jan 28 18:25:23 volumio sudo[4625]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio volumio[1193]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::volumioStop Jan 28 18:25:23 volumio volumio[1193]: info: CoreStateMachine::stop Jan 28 18:25:23 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 18:25:23 volumio volumio[1193]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Jan 28 18:25:23 volumio volumiobt[4638]: INFO [BTSTART] Ensuring Bluetooth directory exists... Jan 28 18:25:23 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 18:25:23 volumio volumio[1193]: info: Starting Shairport Sync Jan 28 18:25:23 volumio volumio[1193]: info: Preparing to generate the ALSA configuration file Jan 28 18:25:23 volumio volumio[1193]: info: Asound.conf file unchanged, so no further update is needed Jan 28 18:25:23 volumio volumio[1193]: info: Output device has changed, restarting MPD Jan 28 18:25:23 volumio volumio[1193]: info: Output device has changed, restarting Shairport Sync Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:23 volumio sudo[4639]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Jan 28 18:25:23 volumio sudo[4639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio sudo[4639]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio sudo[4642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 18:25:23 volumio sudo[4642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio sudo[4647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 18:25:23 volumio sudo[4644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 18:25:23 volumio sudo[4650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Jan 28 18:25:23 volumio sudo[4644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio sudo[4650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio sudo[4647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio sudo[4650]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio sudo[4644]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio volumiobt[4657]: INFO [BTSTART] Powering on Bluetooth if needed... Jan 28 18:25:23 volumio volumio[1193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 18:25:23 volumio volumio[1193]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: QobuzConnect: setDeactiveState invoked Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:23 volumio volumio[1193]: info: Preparing to generate the ALSA configuration file Jan 28 18:25:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 18:25:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 18:25:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 18:25:23 volumio systemd[1]: shairport-sync.service: Consumed 3.178s CPU time. Jan 28 18:25:23 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 18:25:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 18:25:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 18:25:23 volumio volumiobt[4662]: No default controller available Jan 28 18:25:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 18:25:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: MPD Permissions set Jan 28 18:25:23 volumio sudo[4670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 28 18:25:23 volumio sudo[4670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 18:25:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 18:25:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 18:25:23 volumio sudo[4642]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio sudo[4670]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:23 volumio volumio[1193]: info: Executing endpoint qc_getconfig Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 28 18:25:23 volumio sudo[4675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 28 18:25:23 volumio sudo[4675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:23 volumio qobuz-connect[4623]: 20260128 18:25:23.962 [4623.4623] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 28 18:25:23 volumio volumio[1193]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 28 18:25:23 volumio volumio[1193]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 28 18:25:23 volumio volumio[1193]: info: Shairport-Sync Started Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 28 18:25:23 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 28 18:25:23 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:23 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 18:25:23 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 28 18:25:23 volumio sudo[4673]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 18:25:23 volumio sudo[4673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 18:25:24 volumio sudo[4675]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio sudo[4673]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: Asound.conf file unchanged, so no further update is needed Jan 28 18:25:24 volumio volumio[1193]: info: Output device has changed, restarting MPD Jan 28 18:25:24 volumio volumio[1193]: info: Output device has changed, restarting Shairport Sync Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:24 volumio sudo[4706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 18:25:24 volumio sudo[4706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:24 volumio sudo[4708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 18:25:24 volumio sudo[4708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:24 volumio sudo[4706]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio volumio[1193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 18:25:24 volumio volumio[1193]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: QobuzConnect: setDeactiveState invoked Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:24 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 18:25:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 18:25:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 18:25:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 18:25:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: Updating MyVolumio device info Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:24 volumio sudo[4723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 28 18:25:24 volumio sudo[4723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 18:25:24 volumio volumio[1193]: info: MPD Permissions set Jan 28 18:25:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 18:25:24 volumio volumio[1193]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 28 18:25:24 volumio volumio[1193]: info: QobuzConnect: setDeactiveState invoked Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:24 volumio sudo[4723]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio volumio[1193]: info: Executing endpoint qc_getconfig Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 28 18:25:24 volumio sudo[4726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 28 18:25:24 volumio sudo[4726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:24 volumio qobuz-connect[4695]: 20260128 18:25:24.135 [4695.4695] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 28 18:25:24 volumio volumio[1193]: info: Starting Shairport Sync Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 28 18:25:24 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 28 18:25:24 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 28 18:25:24 volumio sudo[4733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 18:25:24 volumio sudo[4733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 18:25:24 volumio sudo[4725]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 18:25:24 volumio sudo[4725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 18:25:24 volumio sudo[4725]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 18:25:24 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 28 18:25:24 volumio sudo[4726]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio volumio[1193]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 28 18:25:24 volumio volumio[1193]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 28 18:25:24 volumio volumio[1193]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 28 18:25:24 volumio volumio[1193]: info: QobuzConnect: setDeactiveState invoked Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 18:25:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 18:25:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 18:25:24 volumio volumio[1193]: info: Starting Shairport Sync Jan 28 18:25:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 18:25:24 volumio sudo[4733]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio sudo[4747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 18:25:24 volumio sudo[4747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:24 volumio volumio[1193]: info: Executing endpoint qc_getconfig Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 28 18:25:24 volumio qobuz-connect[4735]: 20260128 18:25:24.255 [4735.4735] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 28 18:25:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 18:25:24 volumio volumio[1193]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 28 18:25:24 volumio volumio[1193]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 28 18:25:24 volumio volumio[1193]: info: Shairport-Sync Started Jan 28 18:25:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 18:25:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 18:25:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 18:25:24 volumio sudo[4747]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio volumio[1193]: info: Shairport-Sync Started Jan 28 18:25:24 volumio qobuz-connect[4735]: 20260128 18:25:24.322 [4735.4735] INFO VolumeManager: [0x559369bd8090]: Setting new playback volume: 75 Jan 28 18:25:24 volumio qobuz-connect[4735]: 20260128 18:25:24.322 [4735.4735] INFO VolumeManager: [0x559369bd8090]: Setting new mute state: 0 Jan 28 18:25:24 volumio qobuz-connect[4735]: 20260128 18:25:24.322 [4735.4735] INFO QobuzConnect: [0x559369bd92b0]: Client initialized! Jan 28 18:25:24 volumio qobuz-connect[4735]: 20260128 18:25:24.322 [4735.4735] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 28 18:25:24 volumio qobuz-connect[4735]: 20260128 18:25:24.326 [4735.4735] INFO LocalConfigManager: [0x559369bd7380]: Starting Local Configuration server Jan 28 18:25:24 volumio qobuz-connect[4735]: 20260128 18:25:24.326 [4735.4735] INFO SampleApp: Starting Local configuration server Jan 28 18:25:24 volumio qobuz-connect[4735]: 20260128 18:25:24.327 [4735.4735] INFO SampleApp: Connected to UNIX socket client 0x559369bad990 Jan 28 18:25:24 volumio qobuz-connect[4735]: 20260128 18:25:24.406 [4735.4735] INFO SampleApp: Playback volume changed: 75 Jan 28 18:25:24 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:24 volumio mpd[4744]: 2026-01-28T18:25:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 18:25:24 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 18:25:24 volumio sudo[4584]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio sudo[4708]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio sudo[4647]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:24 volumio volumio[1193]: error: MPD error: The expression evaluated to a falsy value: Jan 28 18:25:24 volumio volumio[1193]: assert.ok(self.idling) Jan 28 18:25:24 volumio volumio[1193]: error: The expression evaluated to a falsy value: Jan 28 18:25:24 volumio volumio[1193]: assert.ok(self.idling) Jan 28 18:25:24 volumio volumio[1193]: error: MPD error: The expression evaluated to a falsy value: Jan 28 18:25:24 volumio volumio[1193]: assert.ok(self.idling) Jan 28 18:25:24 volumio volumio[1193]: error: The expression evaluated to a falsy value: Jan 28 18:25:24 volumio volumio[1193]: assert.ok(self.idling) Jan 28 18:25:24 volumio volumio[1193]: error: updateQueue error: null Jan 28 18:25:24 volumio volumiobt[4781]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Jan 28 18:25:24 volumio volumiobt[4782]: [83B blob data] Jan 28 18:25:24 volumio volumiobt[4782]: No default controller available Jan 28 18:25:24 volumio volumiobt[4782]: [bluetoothctl]> pairable on Jan 28 18:25:24 volumio volumiobt[4782]: No default controller available Jan 28 18:25:24 volumio volumiobt[4782]: [113B blob data] Jan 28 18:25:24 volumio volumiobt[4782]: [bluetoothctl]> Jan 28 18:25:24 volumio volumiobt[4783]: INFO [BTSTART] Registering Bluetooth agent... Jan 28 18:25:24 volumio volumiobt[4785]: No agent is registered Jan 28 18:25:24 volumio volumiobt[4786]: INFO [BTSTART] Agent registered successfully. Jan 28 18:25:24 volumio volumiobt[4787]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Jan 28 18:25:25 volumio volumiobt[4788]: INFO:a2dp-agent:Connecting to system bus. Jan 28 18:25:25 volumio volumiobt[4788]: ERROR:a2dp-agent:No Bluetooth adapter found: Bluetooth adapter not found Jan 28 18:25:25 volumio volumiobt[4790]: INFO [BTSTART] Disconnecting all Bluetooth devices... Jan 28 18:25:25 volumio volumiobt[4795]: INFO [BTSTART] Unregistering Bluetooth agent... Jan 28 18:25:25 volumio volumiobt[4796]: No agent is registered Jan 28 18:25:25 volumio volumiobt[4797]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Jan 28 18:25:25 volumio volumiobt[4799]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Jan 28 18:25:25 volumio systemd[1]: volumiobt.service: Deactivated successfully. Jan 28 18:25:26 volumio volumio[1193]: info: TidalConnect service stoped! Jan 28 18:25:26 volumio volumio[1193]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 28 18:25:26 volumio volumio[1193]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 28 18:25:26 volumio sudo[4811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 28 18:25:26 volumio sudo[4811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 18:25:26 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 28 18:25:26 volumio sudo[4811]: pam_unix(sudo:session): session closed for user root Jan 28 18:25:26 volumio volumio[1193]: info: Executing endpoint tc_getconfig Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 28 18:25:26 volumio vtcs[4814]: STARTING TidalConnect services, version: 1.5.2.56 Jan 28 18:25:26 volumio vtcs[4814]: STARTED TidalConnect services. Jan 28 18:25:26 volumio volumio[1193]: info: Executing endpoint tc_connect Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 28 18:25:26 volumio volumio[1193]: info: Connecting to TidalConnect Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::pushState Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output update for this device Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:26 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Jan 28 18:25:26 volumio volumio[1193]: 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 28 18:25:26 volumio volumio[1193]: verbose: CURRENT POSITION 0 Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::syncState stateService stop Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus stop Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::pushState Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output update for this device Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:26 volumio volumio[1193]: info: No code Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::pushState Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output update for this device Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::pushState Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output update for this device Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:26 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Jan 28 18:25:26 volumio volumio[1193]: 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 28 18:25:26 volumio volumio[1193]: verbose: CURRENT POSITION 0 Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::syncState stateService stop Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus stop Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::pushState Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output update for this device Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:26 volumio volumio[1193]: info: No code Jan 28 18:25:26 volumio volumio[1193]: info: CoreStateMachine::pushState Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output update for this device Jan 28 18:25:26 volumio volumio[1193]: info: MRS: Pushing multiroomSync output Jan 28 18:25:26 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:26 volumio volumio[1193]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.qVpEs47NLGeh74icSrEheuYIf2C3.c822d41856b4cf2b06dddc2733d3374d.state.status' Jan 28 18:25:26 volumio volumio[1193]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.qVpEs47NLGeh74icSrEheuYIf2C3.c822d41856b4cf2b06dddc2733d3374d.state.status' Jan 28 18:25:26 volumio volumio[1193]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.qVpEs47NLGeh74icSrEheuYIf2C3.c822d41856b4cf2b06dddc2733d3374d.state.status' Jan 28 18:25:26 volumio volumio[1193]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.qVpEs47NLGeh74icSrEheuYIf2C3.c822d41856b4cf2b06dddc2733d3374d.state.status' Jan 28 18:25:28 volumio volumio[1193]: info: MRS: Getting audio outputs on start Jan 28 18:25:28 volumio volumio[1193]: info: MRS: Requesting all other devices output Jan 28 18:25:29 volumio volumio[1193]: info: TidalConnect service started! Jan 28 18:25:32 volumio volumio[1193]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: Received Get System Info Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 18:25:32 volumio volumio[1193]: info: Discovery: Getting this device information Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: Received Get System Info Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 18:25:32 volumio volumio[1193]: info: Discovery: Getting this device information Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: Received Get System Info Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 18:25:32 volumio volumio[1193]: info: Discovery: Getting this device information Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: Received Get System Info Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 18:25:32 volumio volumio[1193]: info: Discovery: Getting this device information Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 28 18:25:32 volumio volumio[1193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 28 18:25:32 volumio volumio[1193]: info: Received Get System Info Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 18:25:32 volumio volumio[1193]: info: Discovery: Getting this device information Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: Listing playlists Jan 28 18:25:32 volumio volumio[1193]: info: Received Get System Info Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 18:25:32 volumio volumio[1193]: info: Discovery: Getting this device information Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: Received Get System Info Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 18:25:32 volumio volumio[1193]: info: Discovery: Getting this device information Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: Received Get System Info Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 18:25:32 volumio volumio[1193]: info: Discovery: Getting this device information Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: Received Get System Info Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 18:25:32 volumio volumio[1193]: info: Discovery: Getting this device information Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 18:25:32 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:32 volumio volumio[1193]: info: Successfully Added MyVolumio device Jan 28 18:25:33 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Jan 28 18:25:35 volumio volumio[1193]: info: Executing endpoint metavolumio Jan 28 18:25:35 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 18:25:35 volumio volumio[1193]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 18:25:35 volumio volumio[1193]: AxiosError: connect EHOSTUNREACH 192.168.68.68:1400 Jan 28 18:25:35 volumio volumio[1193]: at AxiosError.from (/myvolumio/plugins/audio_interface/multiroom/node_modules/axios/dist/node/axios.cjs:914:14) Jan 28 18:25:35 volumio volumio[1193]: at RedirectableRequest.handleRequestError (/myvolumio/plugins/audio_interface/multiroom/node_modules/axios/dist/node/axios.cjs:3515:25) Jan 28 18:25:35 volumio volumio[1193]: at RedirectableRequest.emit (node:events:514:28) Jan 28 18:25:35 volumio volumio[1193]: at eventHandlers. (/myvolumio/plugins/audio_interface/multiroom/node_modules/follow-redirects/index.js:49:24) Jan 28 18:25:35 volumio volumio[1193]: at ClientRequest.emit (node:events:514:28) Jan 28 18:25:35 volumio volumio[1193]: at Socket.socketErrorListener (node:_http_client:495:9) Jan 28 18:25:35 volumio volumio[1193]: at Socket.emit (node:events:514:28) Jan 28 18:25:35 volumio volumio[1193]: at emitErrorNT (node:internal/streams/destroy:151:8) Jan 28 18:25:35 volumio volumio[1193]: at emitErrorCloseNT (node:internal/streams/destroy:116:3) Jan 28 18:25:35 volumio volumio[1193]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Jan 28 18:25:35 volumio volumio[1193]: at Axios.request (/myvolumio/plugins/audio_interface/multiroom/node_modules/axios/dist/node/axios.cjs:4731:41) Jan 28 18:25:35 volumio volumio[1193]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Jan 28 18:25:35 volumio volumio[1193]: port: 1400, Jan 28 18:25:35 volumio volumio[1193]: address: '192.168.68.68', Jan 28 18:25:35 volumio volumio[1193]: syscall: 'connect', Jan 28 18:25:35 volumio volumio[1193]: code: 'EHOSTUNREACH', Jan 28 18:25:35 volumio volumio[1193]: errno: -113, Jan 28 18:25:35 volumio volumio[1193]: config: { Jan 28 18:25:35 volumio volumio[1193]: transitional: { Jan 28 18:25:35 volumio volumio[1193]: silentJSONParsing: true, Jan 28 18:25:35 volumio volumio[1193]: forcedJSONParsing: true, Jan 28 18:25:35 volumio volumio[1193]: clarifyTimeoutError: false Jan 28 18:25:35 volumio volumio[1193]: }, Jan 28 18:25:35 volumio volumio[1193]: adapter: [ 'xhr', 'http', 'fetch' ], Jan 28 18:25:35 volumio volumio[1193]: transformRequest: [ [Function: transformRequest] ], Jan 28 18:25:35 volumio volumio[1193]: transformResponse: [ [Function: transformResponse] ], Jan 28 18:25:35 volumio volumio[1193]: timeout: 0, Jan 28 18:25:35 volumio volumio[1193]: xsrfCookieName: 'XSRF-TOKEN', Jan 28 18:25:35 volumio volumio[1193]: xsrfHeaderName: 'X-XSRF-TOKEN', Jan 28 18:25:35 volumio volumio[1193]: maxContentLength: -1, Jan 28 18:25:35 volumio volumio[1193]: maxBodyLength: -1, Jan 28 18:25:35 volumio volumio[1193]: env: { FormData: [Function], Blob: [class Blob] }, Jan 28 18:25:35 volumio volumio[1193]: validateStatus: [Function: validateStatus], Jan 28 18:25:35 volumio volumio[1193]: headers: Object [AxiosHeaders] { Jan 28 18:25:35 volumio volumio[1193]: Accept: 'application/json, text/plain, */*', Jan 28 18:25:35 volumio volumio[1193]: 'Content-Type': undefined, Jan 28 18:25:35 volumio volumio[1193]: 'User-Agent': 'axios/1.13.2', Jan 28 18:25:35 volumio volumio[1193]: 'Accept-Encoding': 'gzip, compress, deflate, br' Jan 28 18:25:35 volumio volumio[1193]: }, Jan 28 18:25:35 volumio volumio[1193]: url: 'http://192.168.68.68:1400/xml/device_description.xml', Jan 28 18:25:35 volumio volumio[1193]: allowAbsoluteUrls: true, Jan 28 18:25:35 volumio volumio[1193]: method: 'get', Jan 28 18:25:35 volumio volumio[1193]: data: undefined Jan 28 18:25:35 volumio volumio[1193]: }, Jan 28 18:25:35 volumio volumio[1193]: request: Writable { Jan 28 18:25:35 volumio volumio[1193]: _writableState: WritableState { Jan 28 18:25:35 volumio volumio[1193]: objectMode: false, Jan 28 18:25:35 volumio volumio[1193]: highWaterMark: 16384, Jan 28 18:25:35 volumio volumio[1193]: finalCalled: false, Jan 28 18:25:35 volumio volumio[1193]: needDrain: false, Jan 28 18:25:35 volumio volumio[1193]: ending: false, Jan 28 18:25:35 volumio volumio[1193]: ended: false, Jan 28 18:25:35 volumio volumio[1193]: finished: false, Jan 28 18:25:35 volumio volumio[1193]: destroyed: false, Jan 28 18:25:35 volumio volumio[1193]: decodeStrings: true, Jan 28 18:25:35 volumio volumio[1193]: defaultEncoding: 'utf8', Jan 28 18:25:35 volumio volumio[1193]: length: 0, Jan 28 18:25:35 volumio volumio[1193]: writing: false, Jan 28 18:25:35 volumio volumio[1193]: corked: 0, Jan 28 18:25:35 volumio volumio[1193]: sync: true, Jan 28 18:25:35 volumio volumio[1193]: bufferProcessing: false, Jan 28 18:25:35 volumio volumio[1193]: onwrite: [Function: bound onwrite], Jan 28 18:25:35 volumio volumio[1193]: writecb: null, Jan 28 18:25:35 volumio volumio[1193]: writelen: 0, Jan 28 18:25:35 volumio volumio[1193]: afterWriteTickInfo: null, Jan 28 18:25:35 volumio volumio[1193]: buffered: [], Jan 28 18:25:35 volumio volumio[1193]: bufferedIndex: 0, Jan 28 18:25:35 volumio volumio[1193]: allBuffers: true, Jan 28 18:25:35 volumio volumio[1193]: allNoop: true, Jan 28 18:25:35 volumio volumio[1193]: pendingcb: 0, Jan 28 18:25:35 volumio volumio[1193]: constructed: true, Jan 28 18:25:35 volumio volumio[1193]: prefinished: false, Jan 28 18:25:35 volumio volumio[1193]: errorEmitted: false, Jan 28 18:25:35 volumio volumio[1193]: emitClose: true, Jan 28 18:25:35 volumio volumio[1193]: autoDestroy: true, Jan 28 18:25:35 volumio volumio[1193]: errored: null, Jan 28 18:25:35 volumio volumio[1193]: closed: false, Jan 28 18:25:35 volumio volumio[1193]: closeEmitted: false, Jan 28 18:25:35 volumio volumio[1193]: [Symbol(kOnFinished)]: [] Jan 28 18:25:35 volumio volumio[1193]: }, Jan 28 18:25:35 volumio volumio[1193]: _events: [Object: null prototype] { Jan 28 18:25:35 volumio volumio[1193]: response: [Function: handleResponse], Jan 28 18:25:35 volumio volumio[1193]: error: [Function: handleRequestError], Jan 28 18:25:35 volumio volumio[1193]: socket: [Array] Jan 28 18:25:35 volumio volumio[1193]: }, Jan 28 18:25:35 volumio volumio[1193]: _eventsCount: 3, Jan 28 18:25:35 volumio volumio[1193]: _maxListeners: undefined, Jan 28 18:25:35 volumio volumio[1193]: _options: { Jan 28 18:25:35 volumio volumio[1193]: maxRedirects: 21, Jan 28 18:25:35 volumio volumio[1193]: maxBodyLength: Infinity, Jan 28 18:25:35 volumio volumio[1193]: protocol: 'http:', Jan 28 18:25:35 volumio volumio[1193]: path: '/xml/device_description.xml', Jan 28 18:25:35 volumio volumio[1193]: method: 'GET', Jan 28 18:25:35 volumio volumio[1193]: headers: [Object: null prototype], Jan 28 18:25:35 volumio volumio[1193]: agents: [Object], Jan 28 18:25:35 volumio volumio[1193]: auth: undefined, Jan 28 18:25:35 volumio volumio[1193]: family: undefined, Jan 28 18:25:35 volumio volumio[1193]: beforeRedirect: [Function: dispatchBeforeRedirect], Jan 28 18:25:35 volumio volumio[1193]: beforeRedirects: [Object], Jan 28 18:25:35 volumio volumio[1193]: http2Options: undefined, Jan 28 18:25:35 volumio volumio[1193]: hostname: '192.168.68.68', Jan 28 18:25:35 volumio volumio[1193]: port: '1400', Jan 28 18:25:35 volumio volumio[1193]: agent: undefined, Jan 28 18:25:35 volumio volumio[1193]: nativeProtocols: [Object], Jan 28 18:25:35 volumio volumio[1193]: pathname: '/xml/device_description.xml' Jan 28 18:25:35 volumio volumio[1193]: }, Jan 28 18:25:35 volumio volumio[1193]: _ended: true, Jan 28 18:25:35 volumio volumio[1193]: _ending: true, Jan 28 18:25:35 volumio volumio[1193]: _redirectCount: 0, Jan 28 18:25:35 volumio volumio[1193]: _redirects: [], Jan 28 18:25:35 volumio volumio[1193]: _requestBodyLength: 0, Jan 28 18:25:35 volumio volumio[1193]: _requestBodyBuffers: [], Jan 28 18:25:35 volumio volumio[1193]: _onNativeResponse: [Function (anonymous)], Jan 28 18:25:35 volumio volumio[1193]: _currentRequest: ClientRequest { Jan 28 18:25:35 volumio volumio[1193]: _events: [Object: null prototype], Jan 28 18:25:35 volumio volumio[1193]: _eventsCount: 7, Jan 28 18:25:35 volumio volumio[1193]: _maxListeners: undefined, Jan 28 18:25:35 volumio volumio[1193]: outputData: [], Jan 28 18:25:35 volumio volumio[1193]: outputSize: 0, Jan 28 18:25:35 volumio volumio[1193]: writable: true, Jan 28 18:25:35 volumio volumio[1193]: destroyed: false, Jan 28 18:25:35 volumio volumio[1193]: _last: true, Jan 28 18:25:35 volumio volumio[1193]: chunkedEncoding: false, Jan 28 18:25:35 volumio volumio[1193]: shouldKeepAlive: true, Jan 28 18:25:35 volumio volumio[1193]: maxRequestsOnConnectionReached: false, Jan 28 18:25:35 volumio volumio[1193]: _defaultKeepAlive: true, Jan 28 18:25:35 volumio volumio[1193]: useChunkedEncodingByDefault: false, Jan 28 18:25:35 volumio volumio[1193]: sendDate: false, Jan 28 18:25:35 volumio volumio[1193]: _removedConnection: false, Jan 28 18:25:35 volumio volumio[1193]: _removedContLen: false, Jan 28 18:25:35 volumio volumio[1193]: _removedTE: false, Jan 28 18:25:35 volumio volumio[1193]: strictContentLength: false, Jan 28 18:25:35 volumio volumio[1193]: _contentLength: 0, Jan 28 18:25:35 volumio volumio[1193]: _hasBody: true, Jan 28 18:25:35 volumio volumio[1193]: _trailer: '', Jan 28 18:25:35 volumio volumio[1193]: finished: true, Jan 28 18:25:35 volumio volumio[1193]: _headerSent: true, Jan 28 18:25:35 volumio volumio[1193]: _closed: false, Jan 28 18:25:35 volumio volumio[1193]: socket: [Socket], Jan 28 18:25:35 volumio volumio[1193]: _header: 'GET /xml/device_description.xml HTTP/1.1\r\n' + Jan 28 18:25:35 volumio volumio[1193]: 'Accept: application/json, text/plain, */*\r\n' + Jan 28 18:25:35 volumio volumio[1193]: 'User-Agent: axios/1.13.2\r\n' + Jan 28 18:25:35 volumio volumio[1193]: 'Accept-Encoding: gzip, compress, deflate, br\r\n' + Jan 28 18:25:35 volumio volumio[1193]: 'Host: 192.168.68.68:1400\r\n' + Jan 28 18:25:35 volumio volumio[1193]: 'Connection: keep-alive\r\n' + Jan 28 18:25:35 volumio volumio[1193]: '\r\n', Jan 28 18:25:35 volumio volumio[1193]: _keepAliveTimeout: 0, Jan 28 18:25:35 volumio volumio[1193]: _onPendingData: [Function: nop], Jan 28 18:25:35 volumio volumio[1193]: agent: [Agent], Jan 28 18:25:35 volumio volumio[1193]: socketPath: undefined, Jan 28 18:25:35 volumio volumio[1193]: method: 'GET', Jan 28 18:25:35 volumio volumio[1193]: maxHeaderSize: undefined, Jan 28 18:25:35 volumio volumio[1193]: insecureHTTPParser: undefined, Jan 28 18:25:35 volumio volumio[1193]: joinDuplicateHeaders: undefined, Jan 28 18:25:35 volumio volumio[1193]: path: '/xml/device_description.xml', Jan 28 18:25:35 volumio volumio[1193]: _ended: false, Jan 28 18:25:35 volumio volumio[1193]: res: null, Jan 28 18:25:35 volumio volumio[1193]: aborted: false, Jan 28 18:25:35 volumio volumio[1193]: timeoutCb: [Function: emitRequestTimeout], Jan 28 18:25:35 volumio volumio[1193]: upgradeOrConnect: false, Jan 28 18:25:35 volumio volumio[1193]: parser: null, Jan 28 18:25:35 volumio volumio[1193]: maxHeadersCount: null, Jan 28 18:25:35 volumio volumio[1193]: reusedSocket: false, Jan 28 18:25:35 volumio volumio[1193]: host: '192.168.68.68', Jan 28 18:25:35 volumio volumio[1193]: protocol: 'http:', Jan 28 18:25:35 volumio volumio[1193]: _redirectable: [Circular *1], Jan 28 18:25:35 volumio volumio[1193]: [Symbol(kCapture)]: false, Jan 28 18:25:35 volumio volumio[1193]: [Symbol(kBytesWritten)]: 0, Jan 28 18:25:35 volumio volumio[1193]: [Symbol(kNeedDrain)]: false, Jan 28 18:25:35 volumio volumio[1193]: [Symbol(corked)]: 0, Jan 28 18:25:35 volumio volumio[1193]: [Symbol(kOutHeaders)]: [Object: null prototype], Jan 28 18:25:35 volumio volumio[1193]: [Symbol(errored)]: null, Jan 28 18:25:35 volumio volumio[1193]: [Symbol(kHighWaterMark)]: 16384, Jan 28 18:25:35 volumio volumio[1193]: [Symbol(kRejectNonStandardBodyWrites)]: false, Jan 28 18:25:35 volumio volumio[1193]: [Symbol(kUniqueHeaders)]: null Jan 28 18:25:35 volumio volumio[1193]: }, Jan 28 18:25:35 volumio volumio[1193]: _currentUrl: 'http://192.168.68.68:1400/xml/device_description.xml', Jan 28 18:25:35 volumio volumio[1193]: _timeout: null, Jan 28 18:25:35 volumio volumio[1193]: [Symbol(kCapture)]: false Jan 28 18:25:35 volumio volumio[1193]: }, Jan 28 18:25:35 volumio volumio[1193]: [cause]: Error: connect EHOSTUNREACH 192.168.68.68:1400 Jan 28 18:25:35 volumio volumio[1193]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 28 18:25:35 volumio volumio[1193]: errno: -113, Jan 28 18:25:35 volumio volumio[1193]: code: 'EHOSTUNREACH', Jan 28 18:25:35 volumio volumio[1193]: syscall: 'connect', Jan 28 18:25:35 volumio volumio[1193]: address: '192.168.68.68', Jan 28 18:25:35 volumio volumio[1193]: port: 1400 Jan 28 18:25:35 volumio volumio[1193]: } Jan 28 18:25:35 volumio volumio[1193]: } Jan 28 18:25:35 volumio volumio[1193]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 18:25:35 volumio sudo[4870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 18:24' Jan 28 18:25:35 volumio sudo[4870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"