-- Logs begin at Mon 2024-12-23 00:23:42 CET, end at Sun 2025-02-16 15:42:15 CET. -- Feb 16 15:41:00 rivo volumio[3318]: info: Preload queue cleared Feb 16 15:41:00 rivo volumio[3318]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::ClearQueue Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::stop Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::stPlaybackTimer Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::updateTrackBlock Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::getTrackBlock Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::pushState Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::getTrack 3 Feb 16 15:41:00 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 15:41:00 rivo volumio[3318]: info: CoreCommandRouter::volumioPushState Feb 16 15:41:00 rivo volumio[3318]: info: MRS: Pushing multiroomSync output update for this device Feb 16 15:41:00 rivo volumio[3318]: info: MRS: Pushing multiroomSync output Feb 16 15:41:00 rivo volumio[3318]: info: MRS: Pushing multiroomSync output update for this device Feb 16 15:41:00 rivo volumio[3318]: info: MRS: Pushing multiroomSync output Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::serviceStop Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::getTrack 3 Feb 16 15:41:00 rivo volumio[3318]: info: CoreCommandRouter::serviceStop Feb 16 15:41:00 rivo volumio[3318]: info: [1739716860865] ControllerQobuz::stop Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 16 15:41:00 rivo volumio[3318]: info: ControllerMpd::stop Feb 16 15:41:00 rivo volumio[3318]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::clearPlayQueue Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::saveQueue Feb 16 15:41:00 rivo volumio[3318]: info: CoreCommandRouter::volumioPushQueue Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::addQueueItems Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::addQueueItems Feb 16 15:41:00 rivo volumio[3318]: info: Preload queue cleared Feb 16 15:41:00 rivo volumio[3318]: info: Adding Item to queue: qobuz://song/127402472 Feb 16 15:41:00 rivo volumio[3318]: info: Using cached record of: qobuz://song/127402472 Feb 16 15:41:00 rivo volumio[3318]: info: Adding Item to queue: qobuz://song/127402473 Feb 16 15:41:00 rivo volumio[3318]: info: Using cached record of: qobuz://song/127402473 Feb 16 15:41:00 rivo volumio[3318]: info: Adding Item to queue: qobuz://song/127402474 Feb 16 15:41:00 rivo volumio[3318]: info: Using cached record of: qobuz://song/127402474 Feb 16 15:41:00 rivo volumio[3318]: info: Adding Item to queue: qobuz://song/127402475 Feb 16 15:41:00 rivo volumio[3318]: info: Using cached record of: qobuz://song/127402475 Feb 16 15:41:00 rivo volumio[3318]: info: Adding Item to queue: qobuz://song/127402476 Feb 16 15:41:00 rivo volumio[3318]: info: Using cached record of: qobuz://song/127402476 Feb 16 15:41:00 rivo volumio[3318]: info: CoreCommandRouter::volumioPushQueue Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::saveQueue Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::updateTrackBlock Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::getTrackBlock Feb 16 15:41:00 rivo volumio[3318]: info: CoreCommandRouter::volumioPlay Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::play index 4 Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::addQueueItems Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::addQueueItems Feb 16 15:41:00 rivo volumio[3318]: info: Preload queue cleared Feb 16 15:41:00 rivo volumio[3318]: info: Adding Item to queue: qobuz://song/127402477 Feb 16 15:41:00 rivo volumio[3318]: info: Using cached record of: qobuz://song/127402477 Feb 16 15:41:00 rivo volumio[3318]: info: Adding Item to queue: qobuz://song/127402478 Feb 16 15:41:00 rivo volumio[3318]: info: Using cached record of: qobuz://song/127402478 Feb 16 15:41:00 rivo volumio[3318]: info: Adding Item to queue: qobuz://song/127402479 Feb 16 15:41:00 rivo volumio[3318]: info: Using cached record of: qobuz://song/127402479 Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::stop Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 15:41:00 rivo volumio[3318]: info: CoreCommandRouter::volumioPushQueue Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::saveQueue Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::play index undefined Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::updateTrackBlock Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::getTrackBlock Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:00 rivo volumio[3318]: info: CoreStateMachine::startPlaybackTimer Feb 16 15:41:00 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:00 rivo volumio[3318]: info: [1739716860918] ControllerQobuz::clearAddPlayTrack Feb 16 15:41:00 rivo volumio[3318]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 15:41:00 rivo volumio[3318]: info: Updating RAAT Signal Path Feb 16 15:41:09 rivo volumio[3318]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.149 UA: Mozilla/5.0 (Linux; Android 12; SM-T865 Build/SP2A.220305.013; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.163 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:41:09 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:09 rivo volumio[3318]: info: Received Get System Info Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 15:41:09 rivo volumio[3318]: info: Discovery: Getting this device information Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:41:09 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:41:09 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:41:09 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:41:09 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 15:41:09 rivo volumio[3318]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 16 15:41:09 rivo volumio[3318]: info: Received Get System Info Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 15:41:09 rivo volumio[3318]: info: Discovery: Getting this device information Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:41:09 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:41:09 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:09 rivo volumio[3318]: info: Listing playlists Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::volumioGetQueue Feb 16 15:41:09 rivo volumio[3318]: info: CoreStateMachine::getQueue Feb 16 15:41:09 rivo volumio[3318]: info: CorePlayQueue::getQueue Feb 16 15:41:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 16 15:41:18 rivo volumio[3318]: info: CoreCommandRouter::volumioPlay Feb 16 15:41:18 rivo volumio[3318]: info: CoreStateMachine::play index undefined Feb 16 15:41:18 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 15:41:18 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:18 rivo volumio[3318]: info: CoreStateMachine::startPlaybackTimer Feb 16 15:41:18 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:18 rivo volumio[3318]: info: [1739716878768] ControllerQobuz::clearAddPlayTrack Feb 16 15:41:18 rivo volumio[3318]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 15:41:18 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 16 15:41:23 rivo volumio[3318]: info: CoreCommandRouter::volumioPlay Feb 16 15:41:23 rivo volumio[3318]: info: CoreStateMachine::play index undefined Feb 16 15:41:23 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 15:41:23 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:23 rivo volumio[3318]: info: CoreStateMachine::startPlaybackTimer Feb 16 15:41:23 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:23 rivo volumio[3318]: info: [1739716883990] ControllerQobuz::clearAddPlayTrack Feb 16 15:41:23 rivo volumio[3318]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 15:41:28 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:41:28 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:30 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:41:30 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:41:30 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:41:30 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:41:30 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:41:30 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:41:31 rivo volumio[3318]: info: Retrieving Cloud Streaming UI Feb 16 15:41:31 rivo volumio[3318]: info: Getting Tidal Cloud Configuration Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:41:31 rivo volumio[3318]: info: Getting Qobuz Cloud Configuration Feb 16 15:41:31 rivo volumio[3318]: info: Asking plugin for UI Config Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:41:31 rivo volumio[3318]: info: Getting Spotify Cloud Configuration Feb 16 15:41:31 rivo volumio[3318]: info: Asking plugin for UI Config Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:41:31 rivo volumio[3318]: info: Saving Spotify Acccount Feb 16 15:41:31 rivo volumio[3318]: info: Got it Feb 16 15:41:31 rivo volumio[3318]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 16 15:41:31 rivo volumio[3318]: info: Got Tidal Cloud Configuration Feb 16 15:41:31 rivo volumio[3318]: info: Got it Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 15:41:31 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 16 15:41:35 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 15:41:35 rivo volumio[3318]: info: Enabling MyMusic plugin upnp Feb 16 15:41:35 rivo volumio[3318]: info: Enabling plugin upnp Feb 16 15:41:35 rivo volumio[3318]: info: Loading plugin "upnp"... Feb 16 15:41:35 rivo volumio[3318]: info: [1739716895478] Starting Upmpd Daemon Feb 16 15:41:35 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:41:35 rivo volumio[3318]: info: Done. Feb 16 15:41:38 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:41:38 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:41 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:41:41 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:41:41 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:41:41 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:41:41 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:41:41 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:41:44 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:41:44 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:41:44 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:41:44 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:41:45 rivo sudo[9832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 15:41:45 rivo sudo[9832]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:41:45 rivo sudo[9832]: pam_unix(sudo:session): session closed for user root Feb 16 15:41:45 rivo sudo[9836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 15:41:45 rivo sudo[9836]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:41:45 rivo sudo[9836]: pam_unix(sudo:session): session closed for user root Feb 16 15:41:45 rivo sudo[9840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 16 15:41:45 rivo sudo[9840]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:41:45 rivo systemd[1]: Started UPnP Renderer front-end to MPD. Feb 16 15:41:45 rivo sudo[9840]: pam_unix(sudo:session): session closed for user root Feb 16 15:41:45 rivo volumio[3318]: info: Upmpdcli Daemon Started Feb 16 15:41:45 rivo volumio[9842]: writing RSA key Feb 16 15:41:46 rivo volumio[3318]: info: CoreCommandRouter::volumioPlay Feb 16 15:41:46 rivo volumio[3318]: info: CoreStateMachine::play index undefined Feb 16 15:41:46 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 15:41:46 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:46 rivo volumio[3318]: info: CoreStateMachine::startPlaybackTimer Feb 16 15:41:46 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:46 rivo volumio[3318]: info: [1739716906719] ControllerQobuz::clearAddPlayTrack Feb 16 15:41:46 rivo volumio[3318]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 15:41:51 rivo volumio[3318]: info: CoreCommandRouter::volumioPlay Feb 16 15:41:51 rivo volumio[3318]: info: CoreStateMachine::play index undefined Feb 16 15:41:51 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 15:41:51 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:51 rivo volumio[3318]: info: CoreStateMachine::startPlaybackTimer Feb 16 15:41:51 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:41:51 rivo volumio[3318]: info: [1739716911638] ControllerQobuz::clearAddPlayTrack Feb 16 15:41:51 rivo volumio[3318]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 15:42:00 rivo volumio[3318]: info: CoreCommandRouter::volumioPlay Feb 16 15:42:00 rivo volumio[3318]: info: CoreStateMachine::play index undefined Feb 16 15:42:00 rivo volumio[3318]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 15:42:00 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:42:00 rivo volumio[3318]: info: CoreStateMachine::startPlaybackTimer Feb 16 15:42:00 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:42:00 rivo volumio[3318]: info: [1739716920017] ControllerQobuz::clearAddPlayTrack Feb 16 15:42:00 rivo volumio[3318]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 15:42:02 rivo volumio[3318]: info: CoreCommandRouter::volumioGetState Feb 16 15:42:02 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:42:04 rivo volumio[3318]: info: Retrieving Cloud Streaming UI Feb 16 15:42:04 rivo volumio[3318]: info: Getting Tidal Cloud Configuration Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:42:04 rivo volumio[3318]: info: Getting Qobuz Cloud Configuration Feb 16 15:42:04 rivo volumio[3318]: info: Asking plugin for UI Config Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:42:04 rivo volumio[3318]: info: Getting Spotify Cloud Configuration Feb 16 15:42:04 rivo volumio[3318]: info: Asking plugin for UI Config Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:42:04 rivo volumio[3318]: info: Saving Spotify Acccount Feb 16 15:42:04 rivo volumio[3318]: info: Got it Feb 16 15:42:04 rivo volumio[3318]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 16 15:42:04 rivo volumio[3318]: info: Got Tidal Cloud Configuration Feb 16 15:42:04 rivo volumio[3318]: info: Got it Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 15:42:04 rivo volumio[3318]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 15:42:05 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 15:42:05 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 16 15:42:05 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:42:05 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:42:05 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:42:05 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:42:05 rivo volumio[3318]: info: Executing endpoint metavolumio Feb 16 15:42:05 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 15:42:06 rivo volumio[3318]: info: Disabling MyMusic plugin bluetooth Feb 16 15:42:06 rivo sudo[9924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Feb 16 15:42:06 rivo sudo[9924]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:06 rivo systemd[1]: Stopping Volumio Bluetooth Module... Feb 16 15:42:06 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:06 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:06 rivo volumiobt[9926]: Killing pulse Feb 16 15:42:06 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:06 rivo volumiobt[9926]: Killing BT Pair agent Feb 16 15:42:06 rivo bluetoothd[3207]: Endpoint unregistered: sender=:1.71 path=/MediaEndpoint/A2DPSource Feb 16 15:42:06 rivo bluetoothd[3207]: Endpoint unregistered: sender=:1.71 path=/MediaEndpoint/A2DPSink Feb 16 15:42:06 rivo volumio[3318]: info: Enabling MyMusic plugin bluetooth Feb 16 15:42:06 rivo volumio[3318]: info: Enabling plugin bluetooth Feb 16 15:42:06 rivo volumio[3318]: info: Loading plugin "bluetooth"... Feb 16 15:42:06 rivo volumio[3318]: info: [1739716926802] Starting BluetoothController Feb 16 15:42:06 rivo volumio[3318]: info: PLUGIN START: bluetooth Feb 16 15:42:06 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:42:06 rivo systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Feb 16 15:42:06 rivo systemd[1]: volumiobt.service: Succeeded. Feb 16 15:42:06 rivo systemd[1]: Stopped Volumio Bluetooth Module. Feb 16 15:42:06 rivo sudo[9924]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:06 rivo volumio[3318]: info: Done. Feb 16 15:42:06 rivo sudo[9931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Feb 16 15:42:06 rivo sudo[9931]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:06 rivo sudo[9931]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:06 rivo volumio[3318]: info: Volumio BT Module successfully stopped Feb 16 15:42:06 rivo volumio[3318]: info: Disabling plugin bluetooth Feb 16 15:42:06 rivo volumio[3318]: info: Done. Feb 16 15:42:06 rivo volumio[3318]: info: Bluetooth name changed to Rivo Feb 16 15:42:08 rivo volumio[3318]: info: Disabling MyMusic plugin multiroom Feb 16 15:42:08 rivo volumio[3318]: info: Disabling plugin multiroom Feb 16 15:42:08 rivo volumio[3318]: info: Preparing to generate the ALSA configuration file Feb 16 15:42:08 rivo volumio[3318]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 16 15:42:08 rivo volumio[3318]: info: Reading ALSA contributions from plugins. Feb 16 15:42:08 rivo volumio[3318]: info: Asound.conf file written Feb 16 15:42:08 rivo sudo[9936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 16 15:42:08 rivo sudo[9936]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo sudo[9936]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 16 15:42:08 rivo kernel: Err: can not set rate to 4270967296! Feb 16 15:42:08 rivo kernel: Range[3920000 - 500000000] Feb 16 15:42:08 rivo kernel: out of value, fixed it Feb 16 15:42:08 rivo kernel: id=0 set inskew=0 Feb 16 15:42:08 rivo volumio[3318]: No protocol specified Feb 16 15:42:08 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:08 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 16 15:42:08 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 16 15:42:08 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 16 15:42:08 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 16 15:42:08 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 16 15:42:08 rivo volumio[3318]: info: Output device has changed, restarting MPD Feb 16 15:42:08 rivo sudo[9941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:42:08 rivo sudo[9941]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo volumio[3318]: info: Output device has changed, restarting Shairport Sync Feb 16 15:42:08 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:08 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:42:08 rivo sudo[9941]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo sudo[9944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:42:08 rivo sudo[9944]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo systemd[1]: Stopping Music Player Daemon... Feb 16 15:42:08 rivo volumio[3318]: No protocol specified Feb 16 15:42:08 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:08 rivo volumio[3318]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 15:42:08 rivo volumio[3318]: No protocol specified Feb 16 15:42:08 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:08 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:08 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:08 rivo systemd[1]: mpd.service: Succeeded. Feb 16 15:42:08 rivo systemd[1]: Stopped Music Player Daemon. Feb 16 15:42:08 rivo systemd[1]: Starting Music Player Daemon... Feb 16 15:42:08 rivo volumio[3318]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 16 15:42:08 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:08 rivo vtcs[7001]: [2025-02-16 15:42:08.404] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Feb 16 15:42:08 rivo vtcs[7001]: [2025-02-16 15:42:08.404] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Feb 16 15:42:08 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:08 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:08 rivo sudo[9959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 16 15:42:08 rivo sudo[9959]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo volumio[3318]: info: Done. Feb 16 15:42:08 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Feb 16 15:42:08 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 16 15:42:08 rivo systemd[1]: vtcs.service: Succeeded. Feb 16 15:42:08 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Feb 16 15:42:08 rivo sudo[9959]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo volumio[3318]: info: Enabling MyMusic plugin multiroom Feb 16 15:42:08 rivo volumio[3318]: info: Enabling plugin multiroom Feb 16 15:42:08 rivo volumio[3318]: info: Loading plugin "multiroom"... Feb 16 15:42:08 rivo volumio[3318]: info: Applying required configuration parameters for plugin multiroom Feb 16 15:42:08 rivo sudo[9950]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:42:08 rivo sudo[9950]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo sudo[9968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 16 15:42:08 rivo sudo[9968]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo sudo[9950]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo sudo[9974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 16 15:42:08 rivo sudo[9974]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo sudo[9968]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo sudo[9974]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo volumio[3318]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 16 15:42:08 rivo volumio[3318]: info: MRS: MultiRoom plugin initialized Feb 16 15:42:08 rivo volumio[3318]: info: MRS: STOPPING SNAPCLIENT Feb 16 15:42:08 rivo volumio[3318]: info: MRS: Snap server stop Feb 16 15:42:08 rivo sudo[9992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 16 15:42:08 rivo sudo[9992]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo volumio[3318]: info: MRS: STOPPING volumioStreaming Feb 16 15:42:08 rivo sudo[9995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 16 15:42:08 rivo sudo[9995]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo sudo[9992]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo sudo[9998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 16 15:42:08 rivo sudo[9998]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo sudo[9995]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo volumio[3318]: info: Preparing to generate the ALSA configuration file Feb 16 15:42:08 rivo sudo[10002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 16 15:42:08 rivo sudo[9998]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo sudo[10002]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:08 rivo sudo[10002]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:08 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:09 rivo volumio[3318]: info: MPD Permissions set Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: Not Reporting Auto name since its the default one Feb 16 15:42:09 rivo volumio[3318]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 16 15:42:09 rivo volumio[3318]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 16 15:42:09 rivo volumio[3318]: info: Reading ALSA contributions from plugins. Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 15:42:09 rivo volumio[3318]: info: MRS: Removed streaming files Feb 16 15:42:09 rivo volumio[3318]: info: MRS: volumioStreaming STOPPED Feb 16 15:42:09 rivo volumio[3318]: info: MRS: SNAPSERVER STOPPED Feb 16 15:42:09 rivo volumio[3318]: info: MRS: SNAPCLIENT STOPPED Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 16 15:42:09 rivo volumio[3318]: No protocol specified Feb 16 15:42:09 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:09 rivo volumio[3318]: info: Starting Shairport Sync Feb 16 15:42:09 rivo sudo[10017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 16 15:42:09 rivo sudo[10017]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:09 rivo volumio[3318]: info: Asound.conf file written Feb 16 15:42:09 rivo sudo[10017]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:09 rivo sudo[10020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 15:42:09 rivo sudo[10020]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:09 rivo sudo[10022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 16 15:42:09 rivo sudo[10022]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:09 rivo sudo[10024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 16 15:42:09 rivo sudo[10024]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:09 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 15:42:09 rivo sudo[10024]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:09 rivo sudo[10020]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:09 rivo systemd[1]: Stopping RAAT DAEMON... Feb 16 15:42:09 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 16 15:42:09 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 16 15:42:09 rivo systemd[1]: Stopped RAAT DAEMON. Feb 16 15:42:09 rivo systemd[1]: Started RAAT DAEMON. Feb 16 15:42:09 rivo sudo[10022]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:09 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 16 15:42:09 rivo kernel: Err: can not set rate to 4266967296! Feb 16 15:42:09 rivo kernel: Range[3920000 - 500000000] Feb 16 15:42:09 rivo kernel: out of value, fixed it Feb 16 15:42:09 rivo kernel: id=0 set inskew=0 Feb 16 15:42:09 rivo volumio[3318]: No protocol specified Feb 16 15:42:09 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:09 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 16 15:42:09 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 16 15:42:09 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 16 15:42:09 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 16 15:42:09 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 16 15:42:09 rivo volumio[3318]: info: Output device has changed, restarting MPD Feb 16 15:42:09 rivo sudo[10043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:42:09 rivo sudo[10043]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:09 rivo volumio[3318]: info: Output device has changed, restarting Shairport Sync Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:42:09 rivo sudo[10050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:42:09 rivo sudo[10050]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:09 rivo volumio[3318]: No protocol specified Feb 16 15:42:09 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:09 rivo volumio[3318]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 15:42:09 rivo volumio[3318]: No protocol specified Feb 16 15:42:09 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:09 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo sudo[10043]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:09 rivo volumio[3318]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 16 15:42:09 rivo systemd[1]: mpd.service: Succeeded. Feb 16 15:42:09 rivo systemd[1]: Stopped Music Player Daemon. Feb 16 15:42:09 rivo systemd[1]: Starting Music Player Daemon... Feb 16 15:42:09 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo sudo[10072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 16 15:42:09 rivo sudo[10072]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:09 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo volumio[3318]: info: Preparing to generate the ALSA configuration file Feb 16 15:42:09 rivo volumio[3318]: info: PLUGIN START: multiroom Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 16 15:42:09 rivo volumio[3318]: info: MRS: Pushing multiroomSync output for this device Feb 16 15:42:09 rivo volumio[3318]: info: MRS: Pushing multiroomSync output Feb 16 15:42:09 rivo sudo[10072]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:09 rivo volumio[3318]: info: Adding audio output: Feb 16 15:42:09 rivo volumio[3318]: error: Can't add: Feb 16 15:42:09 rivo volumio[3318]: info: Adding audio output: Feb 16 15:42:09 rivo volumio[3318]: error: Can't add: Feb 16 15:42:09 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:09 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:09 rivo sudo[10070]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:42:09 rivo sudo[10070]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:09 rivo sudo[10070]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:10 rivo sudo[10085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 16 15:42:10 rivo sudo[10085]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:10 rivo volumio[3318]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 16 15:42:10 rivo volumio[3318]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 16 15:42:10 rivo volumio[3318]: info: Reading ALSA contributions from plugins. Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:10 rivo sudo[10085]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:10 rivo sudo[10089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 16 15:42:10 rivo sudo[10089]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:10 rivo systemd[1]: Started Volumio Bluetooth Module. Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:10 rivo sudo[10089]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:10 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:10 rivo volumio[3318]: info: Enabling MyMusic plugin multiroom Feb 16 15:42:10 rivo volumio[3318]: info: Enabling plugin multiroom Feb 16 15:42:10 rivo volumio[3318]: info: Disabling MyMusic plugin multiroom Feb 16 15:42:10 rivo volumio[3318]: info: Loading plugin "multiroom"... Feb 16 15:42:10 rivo volumio[3318]: info: Applying required configuration parameters for plugin multiroom Feb 16 15:42:10 rivo sudo[10093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 16 15:42:10 rivo sudo[10093]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:10 rivo volumiobt[10092]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 16 15:42:10 rivo sudo[10093]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:10 rivo sudo[10098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 16 15:42:10 rivo sudo[10098]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:10 rivo sudo[10098]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:10 rivo volumio[3318]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 16 15:42:10 rivo volumio[3318]: info: MRS: MultiRoom plugin initialized Feb 16 15:42:10 rivo volumio[3318]: info: MRS: STOPPING SNAPCLIENT Feb 16 15:42:10 rivo volumio[3318]: info: MRS: Snap server stop Feb 16 15:42:10 rivo sudo[10114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 16 15:42:10 rivo volumio[3318]: info: MRS: STOPPING volumioStreaming Feb 16 15:42:10 rivo sudo[10114]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:10 rivo sudo[10117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 16 15:42:10 rivo sudo[10117]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:10 rivo sudo[10114]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:10 rivo volumio[3318]: info: Disabling plugin multiroom Feb 16 15:42:10 rivo sudo[10120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 16 15:42:10 rivo sudo[10120]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:10 rivo volumio[3318]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 16 15:42:10 rivo volumio[3318]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 16 15:42:10 rivo volumio[3318]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 16 15:42:10 rivo sudo[10122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 16 15:42:10 rivo sudo[10122]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:10 rivo volumio[3318]: info: MPD Permissions set Feb 16 15:42:10 rivo volumio[3318]: info: Raat Daemon started successfully Feb 16 15:42:10 rivo volumio[3318]: info: Shairport-Sync Started Feb 16 15:42:10 rivo sudo[10122]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:10 rivo sudo[10120]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:10 rivo sudo[10117]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: Not Reporting Auto name since its the default one Feb 16 15:42:10 rivo volumio[3318]: info: MRS: Removed streaming files Feb 16 15:42:10 rivo volumio[3318]: info: MRS: volumioStreaming STOPPED Feb 16 15:42:10 rivo volumio[3318]: info: MRS: SNAPSERVER STOPPED Feb 16 15:42:10 rivo volumio[3318]: info: MRS: SNAPCLIENT STOPPED Feb 16 15:42:10 rivo volumio[3318]: info: Volumio BT Module successfully started Feb 16 15:42:10 rivo volumio[3318]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:10 rivo volumio[3318]: info: Done. Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 16 15:42:10 rivo volumio[3318]: No protocol specified Feb 16 15:42:10 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:10 rivo volumio[3318]: info: Enabling MyMusic plugin bluetooth Feb 16 15:42:10 rivo volumio[3318]: info: Enabling plugin bluetooth Feb 16 15:42:10 rivo volumio[3318]: info: Loading plugin "bluetooth"... Feb 16 15:42:10 rivo volumio[3318]: info: [1739716930940] Starting BluetoothController Feb 16 15:42:10 rivo volumio[3318]: info: PLUGIN START: bluetooth Feb 16 15:42:10 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:42:10 rivo sudo[10144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 16 15:42:10 rivo sudo[10144]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:10 rivo volumio[3318]: info: Done. Feb 16 15:42:10 rivo sudo[10144]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:11 rivo volumio[3318]: info: Executing endpoint restartRAATSocket Feb 16 15:42:11 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 16 15:42:11 rivo sudo[10148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 16 15:42:11 rivo sudo[10148]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:11 rivo sudo[10147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Feb 16 15:42:11 rivo sudo[10147]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:11 rivo sudo[10147]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:11 rivo volumio[3318]: info: Starting Shairport Sync Feb 16 15:42:11 rivo systemd[1]: Stopping RAAT DAEMON... Feb 16 15:42:11 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 16 15:42:11 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 16 15:42:11 rivo systemd[1]: Stopped RAAT DAEMON. Feb 16 15:42:11 rivo systemd[1]: Started RAAT DAEMON. Feb 16 15:42:11 rivo sudo[10148]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:11 rivo sudo[10153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 15:42:11 rivo sudo[10153]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:11 rivo volumio[3318]: info: Bluetooth name changed to Rivo Feb 16 15:42:11 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:42:11 rivo volumio[3318]: info: Enabling MyMusic plugin bluetooth Feb 16 15:42:11 rivo volumio[3318]: info: Enabling plugin bluetooth Feb 16 15:42:11 rivo volumio[3318]: info: Loading plugin "bluetooth"... Feb 16 15:42:11 rivo volumio[3318]: info: [1739716931188] Starting BluetoothController Feb 16 15:42:11 rivo volumio[3318]: info: PLUGIN START: bluetooth Feb 16 15:42:11 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:42:11 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 15:42:11 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 16 15:42:11 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 15:42:11 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 15:42:11 rivo sudo[10153]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:11 rivo volumio[3318]: info: Done. Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:11 rivo sudo[10162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Feb 16 15:42:11 rivo sudo[10162]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:11 rivo sudo[10163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Feb 16 15:42:11 rivo sudo[10163]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:11 rivo sudo[10163]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:11 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:11 rivo volumiobt[10092]: pulseaudio: no process found Feb 16 15:42:11 rivo sudo[10162]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:11 rivo volumio[3318]: info: Raat Daemon started successfully Feb 16 15:42:11 rivo volumio[3318]: info: Bluetooth name changed to Rivo Feb 16 15:42:11 rivo volumio[3318]: info: Shairport-Sync Started Feb 16 15:42:11 rivo volumio[3318]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 16 15:42:11 rivo volumio[3318]: info: Asound.conf file written Feb 16 15:42:11 rivo sudo[10173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 16 15:42:11 rivo sudo[10173]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:11 rivo sudo[10173]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:11 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 16 15:42:11 rivo kernel: Err: can not set rate to 4262967296! Feb 16 15:42:11 rivo kernel: Range[3920000 - 500000000] Feb 16 15:42:11 rivo kernel: out of value, fixed it Feb 16 15:42:11 rivo kernel: id=0 set inskew=0 Feb 16 15:42:11 rivo volumio[3318]: No protocol specified Feb 16 15:42:11 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:11 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 16 15:42:11 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 16 15:42:11 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 16 15:42:11 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 16 15:42:11 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 16 15:42:11 rivo volumio[3318]: info: Output device has changed, restarting MPD Feb 16 15:42:11 rivo sudo[10178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:42:11 rivo sudo[10178]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:11 rivo sudo[10178]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:11 rivo volumio[3318]: info: Output device has changed, restarting Shairport Sync Feb 16 15:42:11 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:11 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:42:11 rivo sudo[10182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:42:11 rivo sudo[10182]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:11 rivo volumio[3318]: No protocol specified Feb 16 15:42:11 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:11 rivo volumio[3318]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 15:42:11 rivo volumio[3318]: No protocol specified Feb 16 15:42:11 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:11 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:11 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:11 rivo pulseaudio[10171]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 16 15:42:11 rivo pulseaudio[10171]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 16 15:42:11 rivo bluetoothd[3207]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource Feb 16 15:42:11 rivo volumiobt[10092]: Applying permissions Feb 16 15:42:11 rivo volumiobt[10092]: Setting BT discoverable and pairable Feb 16 15:42:11 rivo bluetoothd[3207]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSink Feb 16 15:42:11 rivo pulseaudio[10171]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Feb 16 15:42:11 rivo volumio[3318]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 16 15:42:11 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 16 15:42:11 rivo systemd[1]: mpd.service: Succeeded. Feb 16 15:42:11 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:11 rivo systemd[1]: Stopped Music Player Daemon. Feb 16 15:42:12 rivo volumiobt[10092]: Agent registered Feb 16 15:42:12 rivo volumiobt[10092]: [bluetooth]# -e power on Feb 16 15:42:12 rivo volumiobt[10092]: Invalid command in menu main: -e Feb 16 15:42:12 rivo volumiobt[10092]: Feb 16 15:42:12 rivo volumiobt[10092]: Use "help" for a list of available commands in a menu. Feb 16 15:42:12 rivo volumiobt[10092]: Use "menu " if you want to enter any submenu. Feb 16 15:42:12 rivo volumiobt[10092]: Use "back" if you want to return to menu main. Feb 16 15:42:12 rivo volumiobt[10092]: [bluetooth]# agent on Feb 16 15:42:12 rivo volumiobt[10092]: Agent is already registered Feb 16 15:42:12 rivo volumiobt[10092]: [bluetooth]# discoverable on Feb 16 15:42:12 rivo volumiobt[10092]: [bluetooth]# pairable on Feb 16 15:42:12 rivo volumiobt[10092]: [bluetooth]# agent NoInputNoOutput Feb 16 15:42:12 rivo volumiobt[10092]: Agent is already registered Feb 16 15:42:12 rivo volumiobt[10092]: [bluetooth]# default-agent Feb 16 15:42:12 rivo volumiobt[10092]: [bluetooth]# quit Feb 16 15:42:11 rivo sudo[10193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 16 15:42:12 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:11 rivo systemd[1]: Starting Music Player Daemon... Feb 16 15:42:12 rivo volumiobt[10092]: [59B blob data] Feb 16 15:42:11 rivo sudo[10193]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:12 rivo sudo[10193]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:12 rivo volumio[3318]: info: Preparing to generate the ALSA configuration file Feb 16 15:42:12 rivo sudo[10191]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:42:12 rivo sudo[10191]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:12 rivo sudo[10191]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:12 rivo sudo[10207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 16 15:42:12 rivo sudo[10207]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:12 rivo sudo[10207]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:12 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:12 rivo volumio[3318]: info: RAAT: Requesting Headphone Status Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 16 15:42:12 rivo volumio[3318]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 16 15:42:12 rivo volumio[3318]: info: Reading ALSA contributions from plugins. Feb 16 15:42:12 rivo volumio[3318]: info: Enabling MyMusic plugin multiroom Feb 16 15:42:12 rivo volumio[3318]: info: Enabling plugin multiroom Feb 16 15:42:12 rivo volumio[3318]: info: Disabling MyMusic plugin multiroom Feb 16 15:42:12 rivo volumio[3318]: info: Loading plugin "multiroom"... Feb 16 15:42:12 rivo volumio[3318]: info: Applying required configuration parameters for plugin multiroom Feb 16 15:42:12 rivo sudo[10212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 16 15:42:12 rivo sudo[10212]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:12 rivo sudo[10212]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:12 rivo volumio[3318]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 16 15:42:12 rivo volumio[3318]: info: MRS: MultiRoom plugin initialized Feb 16 15:42:12 rivo volumio[3318]: info: MRS: STOPPING SNAPCLIENT Feb 16 15:42:12 rivo volumio[3318]: info: MRS: Snap server stop Feb 16 15:42:12 rivo sudo[10233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 16 15:42:12 rivo sudo[10233]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:12 rivo volumio[3318]: info: MRS: STOPPING volumioStreaming Feb 16 15:42:12 rivo sudo[10236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 16 15:42:12 rivo sudo[10236]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:12 rivo sudo[10233]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:12 rivo sudo[10239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 16 15:42:12 rivo sudo[10239]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:12 rivo sudo[10236]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:12 rivo volumio[3318]: info: Disabling plugin multiroom Feb 16 15:42:12 rivo sudo[10243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 16 15:42:12 rivo sudo[10239]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:12 rivo sudo[10243]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:12 rivo sudo[10243]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:12 rivo volumio[3318]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 16 15:42:12 rivo volumio[3318]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 16 15:42:12 rivo volumio[3318]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 16 15:42:12 rivo volumio[3318]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 16 15:42:12 rivo volumio[3318]: info: MPD Permissions set Feb 16 15:42:12 rivo volumio[3318]: info: TidalConnect service stoped! Feb 16 15:42:12 rivo volumio[3318]: info: TidalConnect service stoped! Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: Not Reporting Auto name since its the default one Feb 16 15:42:12 rivo volumio[3318]: info: Executing endpoint restartRAATSocket Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 16 15:42:12 rivo volumio[3318]: info: MRS: Removed streaming files Feb 16 15:42:12 rivo volumio[3318]: info: MRS: volumioStreaming STOPPED Feb 16 15:42:12 rivo volumio[3318]: info: MRS: SNAPSERVER STOPPED Feb 16 15:42:12 rivo volumio[3318]: info: MRS: SNAPCLIENT STOPPED Feb 16 15:42:12 rivo volumio[3318]: info: TidalConnect service stoped! Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:42:12 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 16 15:42:13 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:13 rivo volumio[3318]: info: CorePlayQueue::getTrack 4 Feb 16 15:42:13 rivo volumio[3318]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 16 15:42:13 rivo volumio[3318]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 16 15:42:13 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 16 15:42:13 rivo sudo[10266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 16 15:42:13 rivo sudo[10266]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:13 rivo systemd[1]: Started Volumio Tidal Connect Service. Feb 16 15:42:13 rivo sudo[10266]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:13 rivo volumio[3318]: No protocol specified Feb 16 15:42:13 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:13 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 15:42:13 rivo sudo[10274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 16 15:42:13 rivo sudo[10274]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:13 rivo volumio[3318]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 16 15:42:13 rivo volumio[3318]: info: TidalConnect service stoped! Feb 16 15:42:13 rivo sudo[10274]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:13 rivo sudo[10276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 16 15:42:13 rivo volumio[3318]: info: Starting Shairport Sync Feb 16 15:42:13 rivo sudo[10276]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:13 rivo systemd[1]: Stopping RAAT DAEMON... Feb 16 15:42:13 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 16 15:42:13 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 16 15:42:13 rivo systemd[1]: Stopped RAAT DAEMON. Feb 16 15:42:13 rivo sudo[10283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 15:42:13 rivo sudo[10283]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:13 rivo systemd[1]: Started RAAT DAEMON. Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:13 rivo sudo[10276]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:13 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 15:42:13 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 16 15:42:13 rivo volumio[3318]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 16 15:42:13 rivo volumio[3318]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 16 15:42:13 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 15:42:13 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 15:42:13 rivo sudo[10283]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:13 rivo sudo[10296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:13 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:13 rivo volumio[3318]: info: Shairport-Sync Started Feb 16 15:42:13 rivo volumio[3318]: (node:3318) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 16 15:42:13 rivo volumio[3318]: (node:3318) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 16 15:42:13 rivo volumio[3318]: (node:3318) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 16 15:42:13 rivo volumio[3318]: (node:3318) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 16 15:42:13 rivo volumio[3318]: (node:3318) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 16 15:42:13 rivo volumio[3318]: info: Raat Daemon started successfully Feb 16 15:42:13 rivo volumio[3318]: info: Asound.conf file written Feb 16 15:42:13 rivo sudo[10296]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:13 rivo sudo[10296]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:13 rivo sudo[10302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 16 15:42:13 rivo sudo[10302]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:13 rivo sudo[10302]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:13 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 16 15:42:13 rivo kernel: Err: can not set rate to 4258967296! Feb 16 15:42:13 rivo kernel: Range[3920000 - 500000000] Feb 16 15:42:13 rivo kernel: out of value, fixed it Feb 16 15:42:13 rivo kernel: id=0 set inskew=0 Feb 16 15:42:13 rivo volumio[3318]: No protocol specified Feb 16 15:42:13 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:13 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 16 15:42:13 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 16 15:42:13 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 16 15:42:13 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 16 15:42:13 rivo volumio[3318]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 16 15:42:13 rivo volumio[3318]: info: Output device has changed, restarting MPD Feb 16 15:42:13 rivo sudo[10307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 15:42:13 rivo sudo[10307]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:13 rivo volumio[3318]: info: Output device has changed, restarting Shairport Sync Feb 16 15:42:13 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:13 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 15:42:13 rivo sudo[10307]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:13 rivo sudo[10310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 15:42:13 rivo sudo[10310]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:13 rivo volumio[3318]: No protocol specified Feb 16 15:42:13 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:13 rivo volumio[3318]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 15:42:13 rivo volumio[3318]: No protocol specified Feb 16 15:42:13 rivo volumio[3318]: xcb_connection_has_error() returned true Feb 16 15:42:13 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:13 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:13 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 16 15:42:13 rivo systemd[1]: mpd.service: Succeeded. Feb 16 15:42:13 rivo systemd[1]: Stopped Music Player Daemon. Feb 16 15:42:13 rivo volumio[3318]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 16 15:42:13 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:13 rivo systemd[1]: Starting Music Player Daemon... Feb 16 15:42:13 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:13 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:13 rivo sudo[10319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 16 15:42:13 rivo sudo[10319]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:13 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:13 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:14 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Feb 16 15:42:14 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 16 15:42:14 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:14 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:14 rivo volumio[3318]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 15:42:14 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 15:42:14 rivo volumio[3318]: info: Preparing to generate the ALSA configuration file Feb 16 15:42:14 rivo sudo[10317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 15:42:14 rivo sudo[10317]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:14 rivo sudo[10317]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:14 rivo sudo[10333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo sudo[10333]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: info: RAAT: Requesting Headphone Status Feb 16 15:42:14 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 16 15:42:14 rivo sudo[10337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 16 15:42:14 rivo sudo[10337]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:14 rivo volumio[3318]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 16 15:42:14 rivo volumio[3318]: info: Reading ALSA contributions from plugins. Feb 16 15:42:14 rivo sudo[10340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo volumio[3318]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 16 15:42:14 rivo sudo[10340]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:14 rivo volumio[3318]: info: Disabling MyMusic plugin upnp Feb 16 15:42:14 rivo sudo[10337]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:14 rivo sudo[10340]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:14 rivo volumio[3318]: info: Enabling MyMusic plugin upnp Feb 16 15:42:14 rivo volumio[3318]: info: Enabling plugin upnp Feb 16 15:42:14 rivo volumio[3318]: info: Loading plugin "upnp"... Feb 16 15:42:14 rivo volumio[3318]: info: [1739716934419] Starting Upmpd Daemon Feb 16 15:42:14 rivo volumio[3318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 15:42:14 rivo volumio[3318]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:42:14 rivo volumio[3318]: Error: listen EADDRINUSE: address already in use :::6599 Feb 16 15:42:14 rivo volumio[3318]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Feb 16 15:42:14 rivo volumio[3318]: at listenInCluster (net.js:1379:12) Feb 16 15:42:14 rivo volumio[3318]: at Server.listen (net.js:1465:7) Feb 16 15:42:14 rivo volumio[3318]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 16 15:42:14 rivo volumio[3318]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 16 15:42:14 rivo volumio[3318]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 16 15:42:14 rivo volumio[3318]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 16 15:42:14 rivo volumio[3318]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Feb 16 15:42:14 rivo volumio[3318]: code: 'EADDRINUSE', Feb 16 15:42:14 rivo volumio[3318]: errno: -98, Feb 16 15:42:14 rivo volumio[3318]: syscall: 'listen', Feb 16 15:42:14 rivo volumio[3318]: address: '::', Feb 16 15:42:14 rivo volumio[3318]: port: 6599 Feb 16 15:42:14 rivo volumio[3318]: } Feb 16 15:42:14 rivo volumio[3318]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 15:42:14 rivo sudo[10343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 16 15:42:14 rivo sudo[10343]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 15:42:14 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Feb 16 15:42:14 rivo systemd[1]: vtcs.service: Succeeded. Feb 16 15:42:14 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Feb 16 15:42:14 rivo sudo[10319]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:14 rivo sudo[10333]: pam_unix(sudo:session): session closed for user root Feb 16 15:42:15 rivo sudo[10361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 15:41 Feb 16 15:42:15 rivo sudo[10361]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"