Jun 11 12:05:24 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 12:05:26 volupi52 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 11 12:05:30 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 12:05:30 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 12:05:33 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 12:05:35 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:37 volupi52 volumio[1451]: info: Executing endpoint metavolumio Jun 11 12:05:37 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 12:05:38 volupi52 sudo[4601]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 12:05:38 volupi52 sudo[4601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:05:38 volupi52 sudo[4601]: pam_unix(sudo:session): session closed for user root Jun 11 12:05:38 volupi52 sudo[4603]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 12:05:38 volupi52 sudo[4603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:05:38 volupi52 sudo[4603]: pam_unix(sudo:session): session closed for user root Jun 11 12:05:38 volupi52 volumio[1451]: verbose: New Socket.io Connection to 192.168.50.209 from 192.168.50.173 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:38 volupi52 volumio[1451]: info: Received Get System Info Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 11 12:05:38 volupi52 volumio[1451]: info: Discovery: Getting this device information Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetVisibleSources Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 11 12:05:38 volupi52 volumio[1451]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 11 12:05:38 volupi52 volumio[1451]: info: Received Get System Info Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 11 12:05:38 volupi52 volumio[1451]: info: Discovery: Getting this device information Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:38 volupi52 volumio[1451]: info: Listing playlists Jun 11 12:05:38 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 11 12:05:40 volupi52 volumio[1451]: info: Received Get System Info Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 11 12:05:40 volupi52 volumio[1451]: info: Discovery: Getting this device information Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_volumio , myVolumioLogout Jun 11 12:05:40 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Subscribed plan changed to free Jun 11 12:05:40 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Device deactivated, disabling myvolumio plugins... Jun 11 12:05:40 volupi52 volumio[1451]: info: ___________ STOPPING MYVOLUMIO PLUGINS ___________ Jun 11 12:05:40 volupi52 volumio[1451]: info: Jun 11 12:05:40 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: [FUNC] onStop Jun 11 12:05:40 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jun 11 12:05:40 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jun 11 12:05:40 volupi52 volumio[1451]: info: Removing METAVOLUMIO REST API Endpoints Jun 11 12:05:40 volupi52 volumio[1451]: info: Removing metavolumio REST Endpoint Jun 11 12:05:40 volupi52 volumio[1451]: info: Removing getSimilarArtists REST Endpoint Jun 11 12:05:40 volupi52 volumio[1451]: info: Removing getSimilarAlbums REST Endpoint Jun 11 12:05:40 volupi52 volumio[1451]: info: Removing getSimilarTracks REST Endpoint Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 12:05:40 volupi52 volumio[1451]: info: Stopping UDEV Watcher for CD Jun 11 12:05:40 volupi52 volumio[1451]: info: Removeing all smart Inputs Jun 11 12:05:40 volupi52 volumio[1451]: info: Stopping AccessToken refresher cron Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 12:05:40 volupi52 volumio[1451]: info: Stopping AccessToken refresher cron for QOBUZ Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Jun 11 12:05:40 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 12:05:40 volupi52 vtcs[2403]: [2025-06-11 12:05:40.946] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 11 12:05:40 volupi52 vtcs[2403]: [2025-06-11 12:05:40.946] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Jun 11 12:05:40 volupi52 vtcs[2403]: [2025-06-11 12:05:40.946] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 11 12:05:40 volupi52 volumio[1451]: info: MyVolumio status changed Jun 11 12:05:40 volupi52 volumio[1451]: info: Streaming services startup Jun 11 12:05:40 volupi52 volumio[1451]: info: Starting Streaming Daemon Jun 11 12:05:40 volupi52 sudo[4607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 12:05:40 volupi52 sudo[4607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:05:40 volupi52 sudo[4609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 11 12:05:40 volupi52 sudo[4609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:05:40 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: Bluetooth audio output stopped Jun 11 12:05:40 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: [FUNC] stopBTStack Jun 11 12:05:40 volupi52 volumio[1451]: info: MYVOLUMIO LOGGED OUT Jun 11 12:05:40 volupi52 volumio[1451]: info: Removing browser output: myVolumio user plan is not superstar Jun 11 12:05:40 volupi52 volumio[1451]: info: Removing audio output: Jun 11 12:05:40 volupi52 volumio[1451]: info: Stoppping Tunnel 1 Jun 11 12:05:40 volupi52 sudo[4613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Jun 11 12:05:40 volupi52 sudo[4613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:05:40 volupi52 sudo[4616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 11 12:05:40 volupi52 sudo[4616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:05:40 volupi52 sudo[4609]: pam_unix(sudo:session): session closed for user root Jun 11 12:05:40 volupi52 volumio[1451]: error: Cannot start Volumio Streaming Daemon Jun 11 12:05:40 volupi52 volumio[1451]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 11 12:05:40 volupi52 volumio[1451]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 11 12:05:41 volupi52 systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Jun 11 12:05:41 volupi52 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 11 12:05:41 volupi52 volumiobt[4619]: INFO [BTSTART] Disconnecting all Bluetooth devices... Jun 11 12:05:41 volupi52 systemd[1]: vtcs.service: Deactivated successfully. Jun 11 12:05:41 volupi52 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 11 12:05:41 volupi52 sudo[4607]: pam_unix(sudo:session): session closed for user root Jun 11 12:05:41 volupi52 bluetoothd[942]: Adv Monitor app :1.76 disconnected from D-Bus Jun 11 12:05:41 volupi52 autossh[2428]: received signal to exit (15) Jun 11 12:05:41 volupi52 systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Jun 11 12:05:41 volupi52 volumiobt[4625]: INFO [BTSTART] Unregistering Bluetooth agent... Jun 11 12:05:41 volupi52 systemd[1]: sshtunnel.service: Deactivated successfully. Jun 11 12:05:41 volupi52 systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 11 12:05:41 volupi52 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. Jun 11 12:05:41 volupi52 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. Jun 11 12:05:41 volupi52 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. Jun 11 12:05:41 volupi52 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. Jun 11 12:05:41 volupi52 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. Jun 11 12:05:41 volupi52 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. Jun 11 12:05:41 volupi52 sudo[4616]: pam_unix(sudo:session): session closed for user root Jun 11 12:05:41 volupi52 volumio[1451]: info: Remote SSH Stopped Jun 11 12:05:41 volupi52 volumiobt[4626]: No agent is registered Jun 11 12:05:41 volupi52 bluetoothd[942]: Adv Monitor app :1.77 disconnected from D-Bus Jun 11 12:05:41 volupi52 volumiobt[4627]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Jun 11 12:05:41 volupi52 volumiobt[4629]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Jun 11 12:05:41 volupi52 systemd[1]: volumiobt.service: Deactivated successfully. Jun 11 12:05:41 volupi52 systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Jun 11 12:05:41 volupi52 sudo[4613]: pam_unix(sudo:session): session closed for user root Jun 11 12:05:41 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: volumiobt.service stopped successfully Jun 11 12:05:41 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: volumiobt.service stopped Jun 11 12:05:41 volupi52 volumio[1451]: verbose: UNSET VOLATILE: Service: undefined Jun 11 12:05:41 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Jun 11 12:05:41 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jun 11 12:05:41 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jun 11 12:05:41 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::resetVolumioState Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::getcurrentVolume Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::resetVolumioState Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::getcurrentVolume Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 12:05:41 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: Volumio state reset after Bluetooth stop Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioStop Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::stop Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioStop Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::stop Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::pushState Jun 11 12:05:41 volupi52 volumio[1451]: info: CorePlayQueue::getTrack 0 Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioPushState Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::updateTrackBlock Jun 11 12:05:41 volupi52 volumio[1451]: info: CorePlayQueue::getTrackBlock Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 12:05:41 volupi52 bluetoothd[942]: Path / reserved for Adv Monitor app :1.78 Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::pushState Jun 11 12:05:41 volupi52 volumio[1451]: info: CorePlayQueue::getTrack 0 Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioPushState Jun 11 12:05:41 volupi52 bluetoothd[942]: Adv Monitor app :1.78 disconnected from D-Bus Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::updateTrackBlock Jun 11 12:05:41 volupi52 volumio[1451]: info: CorePlayQueue::getTrackBlock Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::pushState Jun 11 12:05:41 volupi52 volumio[1451]: info: CorePlayQueue::getTrack 0 Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioPushState Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output Jun 11 12:05:41 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered off Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::pushState Jun 11 12:05:41 volupi52 volumio[1451]: info: CorePlayQueue::getTrack 0 Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioPushState Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output Jun 11 12:05:41 volupi52 volumio[1451]: info: VolumeController::SetAlsaVolume0 Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreStateMachine::pushState Jun 11 12:05:41 volupi52 volumio[1451]: info: CorePlayQueue::getTrack 0 Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 12:05:41 volupi52 volumio[1451]: info: CoreCommandRouter::volumioPushState Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:05:41 volupi52 volumio[1451]: info: MRS: Pushing multiroomSync output Jun 11 12:05:41 volupi52 volumio[1451]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jun 11 12:05:42 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 11 12:05:42 volupi52 volumio[1451]: info: Received Get System Info Jun 11 12:05:42 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 11 12:05:42 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 11 12:05:42 volupi52 volumio[1451]: info: Discovery: Getting this device information Jun 11 12:05:42 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:05:42 volupi52 volumio[1451]: info: CorePlayQueue::getTrack 0 Jun 11 12:05:42 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 11 12:05:42 volupi52 volumio[1451]: info: MyVolumio not started Jun 11 12:05:42 volupi52 volumio[1451]: info: Initializing device activation check Jun 11 12:05:42 volupi52 volumio[1451]: info: MyVolumio not started Jun 11 12:05:42 volupi52 volumio[1451]: info: Initializing device activation check Jun 11 12:05:44 volupi52 volumio[1451]: info: TidalConnect service stoped! Jun 11 12:05:48 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 11 12:06:24 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 11 12:06:24 volupi52 volumio[1451]: info: MyVolumio login type: Token Jun 11 12:06:25 volupi52 volumio[1451]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 11 12:06:25 volupi52 volumio[1451]: info: MyVolumio token set successfully Jun 11 12:06:25 volupi52 volumio[1451]: info: MYVOLUMIO: Adding device Jun 11 12:06:25 volupi52 volumio[1451]: info: MYVOLUMIO: Evaluating Server Jun 11 12:06:26 volupi52 volumio[1451]: info: MyVolumio Plan changed: premium Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 11 12:06:26 volupi52 volumio[1451]: info: Removing browser output: myVolumio user plan is not superstar Jun 11 12:06:26 volupi52 volumio[1451]: info: Removing audio output: Jun 11 12:06:26 volupi52 volumio[1451]: info: MYVOLUMIO: Adding device Jun 11 12:06:26 volupi52 volumio[1451]: info: MYVOLUMIO: Evaluating Server Jun 11 12:06:26 volupi52 volumio[1451]: info: Remote config written successfully Jun 11 12:06:26 volupi52 volumio[1451]: info: Starting Tunnel 1 Jun 11 12:06:26 volupi52 volumio[1451]: info: Starting Tunnel Connection Checker Jun 11 12:06:26 volupi52 sudo[4756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 11 12:06:26 volupi52 sudo[4756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:06:26 volupi52 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 11 12:06:26 volupi52 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. Jun 11 12:06:26 volupi52 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. Jun 11 12:06:26 volupi52 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 11 12:06:26 volupi52 dbus-daemon[943]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.79' (uid=0 pid=4759 comm="timedatectl show --property=NTPSynchronized --valu") Jun 11 12:06:26 volupi52 sudo[4756]: pam_unix(sudo:session): session closed for user root Jun 11 12:06:26 volupi52 autossh[4762]: port set to 0, monitoring disabled Jun 11 12:06:26 volupi52 autossh[4762]: starting ssh (count 1) Jun 11 12:06:26 volupi52 autossh[4762]: ssh child pid is 4765 Jun 11 12:06:26 volupi52 volumio[1451]: info: Remote SSH Started Jun 11 12:06:26 volupi52 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 11 12:06:26 volupi52 volumio[1451]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 11 12:06:26 volupi52 volumio[1451]: info: CoreCommandRouter::volumioGetState Jun 11 12:06:26 volupi52 volumio[1451]: info: CorePlayQueue::getTrack 0 Jun 11 12:06:26 volupi52 dbus-daemon[943]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 11 12:06:26 volupi52 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 11 12:06:26 volupi52 setdatetime-helper.sh[4758]: Time is not synchronized. Attempting to sync... Jun 11 12:06:26 volupi52 volumio[1451]: info: MYVolumio Device enabled Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 11 12:06:26 volupi52 volumio[1451]: info: MyVolumio status changed Jun 11 12:06:26 volupi52 volumio[1451]: info: Streaming services startup Jun 11 12:06:26 volupi52 volumio[1451]: info: Starting Streaming Daemon Jun 11 12:06:26 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 11 12:06:26 volupi52 sudo[4788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 11 12:06:26 volupi52 sudo[4788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 11 12:06:26 volupi52 volumio[1451]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 11 12:06:26 volupi52 sudo[4788]: pam_unix(sudo:session): session closed for user root Jun 11 12:06:26 volupi52 sudo[4792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 11 12:06:26 volupi52 sudo[4792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:06:26 volupi52 sudo[4792]: pam_unix(sudo:session): session closed for user root Jun 11 12:06:26 volupi52 volumiossh-tunnel[4765]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Jun 11 12:06:26 volupi52 volumio[1451]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jun 11 12:06:26 volupi52 volumio[1451]: info: MRS: MultiRoom plugin initialized Jun 11 12:06:26 volupi52 volumio[1451]: info: MRS: STOPPING SNAPCLIENT Jun 11 12:06:26 volupi52 volumio[1451]: info: MRS: Snap server stop Jun 11 12:06:26 volupi52 sudo[4811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 11 12:06:26 volupi52 sudo[4811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:06:26 volupi52 volumio[1451]: info: MRS: STOPPING volumioStreaming Jun 11 12:06:26 volupi52 sudo[4813]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 11 Jun 2025 10:06:26 GMT#015' Jun 11 12:06:26 volupi52 sudo[4813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 11 12:06:26 volupi52 sudo[4814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 11 12:06:26 volupi52 sudo[4814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 11 12:06:26 volupi52 setdatetime-helper.sh[4817]: Wed Jun 11 12:06:26 CEST 2025 Jun 11 12:06:26 volupi52 sudo[4813]: pam_unix(sudo:session): session closed for user root Jun 11 12:06:26 volupi52 setdatetime-helper.sh[4758]: Time synchronized successfully. Jun 11 12:06:26 volupi52 systemd-journald[449]: Time jumped backwards, rotating. Jun 11 12:06:26 volupi52 sudo[4818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 11 12:06:26 volupi52 sudo[4818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:06:26 volupi52 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 11 12:06:26 volupi52 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 11 12:06:26 volupi52 sudo[4821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 11 12:06:26 volupi52 sudo[4821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 11 12:06:26 volupi52 sudo[4821]: pam_unix(sudo:session): session closed for user root Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 11 12:06:26 volupi52 sudo[4811]: pam_unix(sudo:session): session closed for user root Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 11 12:06:26 volupi52 volumio[1451]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 11 12:06:26 volupi52 sudo[4814]: pam_unix(sudo:session): session closed for user root Jun 11 12:06:26 volupi52 volumio[1451]: info: Preparing to generate the ALSA configuration file Jun 11 12:06:26 volupi52 sudo[4818]: pam_unix(sudo:session): session closed for user root Jun 11 12:06:26 volupi52 volumio[1451]: info: Setting Geolocation for MyVolumio to au1 Jun 11 12:06:26 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 12:06:26 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 12:06:26 volupi52 volumio[1451]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 12:06:26 volupi52 volumio[1451]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Jun 11 12:06:26 volupi52 volumio[1451]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 12:06:26 volupi52 volumio[1451]: Error: Unable to resolve or reject the same promise twice Jun 11 12:06:26 volupi52 volumio[1451]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 11 12:06:26 volupi52 volumio[1451]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jun 11 12:06:26 volupi52 volumio[1451]: at Socket.emit (node:events:514:28) Jun 11 12:06:26 volupi52 volumio[1451]: at addChunk (node:internal/streams/readable:343:12) Jun 11 12:06:26 volupi52 volumio[1451]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 11 12:06:26 volupi52 volumio[1451]: at Readable.push (node:internal/streams/readable:253:10) Jun 11 12:06:26 volupi52 volumio[1451]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 11 12:06:26 volupi52 volumio[1451]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 12:06:26 volupi52 sudo[4839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-11 12:05' Jun 11 12:06:26 volupi52 sudo[4839]: 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="ecd46641d85b721d58d1c7edd71dd0a358c8514c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 7 04:21:21 UTC 2025" VOLUMIO_VERSION="4.011" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="97f38114d6fd5ff4ce2816f49b632994"