-- Logs begin at Mon 2024-12-23 00:23:42 CET, end at Mon 2025-03-03 16:57:11 CET. -- Mar 03 16:56:00 rivo volumio[3305]: info: Executing endpoint metavolumio Mar 03 16:56:00 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 16:56:00 rivo volumio[3305]: info: Executing endpoint metavolumio Mar 03 16:56:00 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 16:56:00 rivo volumio[3305]: info: Executing endpoint metavolumio Mar 03 16:56:00 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 16:56:00 rivo systemd[1]: Starting Cleanup of Temporary Directories... Mar 03 16:56:00 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:56:00 rivo systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Mar 03 16:56:00 rivo systemd[1]: Started Cleanup of Temporary Directories. Mar 03 16:56:18 rivo volumio[3305]: info: Preload queue cleared Mar 03 16:56:18 rivo volumio[3305]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 03 16:56:18 rivo volumio[3305]: info: CoreStateMachine::ClearQueue Mar 03 16:56:18 rivo volumio[3305]: info: CoreStateMachine::stop Mar 03 16:56:18 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:18 rivo volumio[3305]: info: CoreStateMachine::stPlaybackTimer Mar 03 16:56:18 rivo volumio[3305]: info: CoreStateMachine::updateTrackBlock Mar 03 16:56:18 rivo volumio[3305]: info: CorePlayQueue::getTrackBlock Mar 03 16:56:18 rivo volumio[3305]: info: CoreStateMachine::pushState Mar 03 16:56:18 rivo volumio[3305]: info: CorePlayQueue::getTrack 1 Mar 03 16:56:18 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 16:56:18 rivo volumio[3305]: info: CoreCommandRouter::volumioPushState Mar 03 16:56:18 rivo volumio[3305]: info: MRS: Pushing multiroomSync output update for this device Mar 03 16:56:18 rivo volumio[3305]: info: MRS: Pushing multiroomSync output Mar 03 16:56:18 rivo volumio[3305]: info: CoreStateMachine::serviceStop Mar 03 16:56:18 rivo volumio[3305]: info: CorePlayQueue::getTrack 1 Mar 03 16:56:18 rivo volumio[3305]: info: CoreCommandRouter::serviceStop Mar 03 16:56:18 rivo volumio[3305]: info: [1741017378965] ControllerQobuz::stop Mar 03 16:56:18 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 03 16:56:18 rivo volumio[3305]: info: ControllerMpd::stop Mar 03 16:56:18 rivo volumio[3305]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 16:56:18 rivo volumio[3305]: info: CorePlayQueue::clearPlayQueue Mar 03 16:56:18 rivo volumio[3305]: info: CorePlayQueue::saveQueue Mar 03 16:56:18 rivo volumio[3305]: info: CoreCommandRouter::volumioPushQueue Mar 03 16:56:18 rivo volumio[3305]: info: CoreStateMachine::addQueueItems Mar 03 16:56:18 rivo volumio[3305]: info: CorePlayQueue::addQueueItems Mar 03 16:56:18 rivo volumio[3305]: info: Preload queue cleared Mar 03 16:56:18 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/174306506 Mar 03 16:56:18 rivo volumio[3305]: info: Using cached record of: qobuz://song/174306506 Mar 03 16:56:18 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/5034555 Mar 03 16:56:18 rivo volumio[3305]: info: Using cached record of: qobuz://song/5034555 Mar 03 16:56:18 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/65170792 Mar 03 16:56:18 rivo volumio[3305]: info: Using cached record of: qobuz://song/65170792 Mar 03 16:56:18 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2879009 Mar 03 16:56:18 rivo volumio[3305]: info: Using cached record of: qobuz://song/2879009 Mar 03 16:56:18 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2878995 Mar 03 16:56:18 rivo volumio[3305]: info: Using cached record of: qobuz://song/2878995 Mar 03 16:56:18 rivo volumio[3305]: info: CoreCommandRouter::volumioPushQueue Mar 03 16:56:18 rivo volumio[3305]: info: CorePlayQueue::saveQueue Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::updateTrackBlock Mar 03 16:56:19 rivo volumio[3305]: info: CorePlayQueue::getTrackBlock Mar 03 16:56:19 rivo volumio[3305]: info: CoreCommandRouter::volumioPlay Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::play index 4 Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::addQueueItems Mar 03 16:56:19 rivo volumio[3305]: info: CorePlayQueue::addQueueItems Mar 03 16:56:19 rivo volumio[3305]: info: Preload queue cleared Mar 03 16:56:19 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2850739 Mar 03 16:56:19 rivo volumio[3305]: info: Using cached record of: qobuz://song/2850739 Mar 03 16:56:19 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2956645 Mar 03 16:56:19 rivo volumio[3305]: info: Using cached record of: qobuz://song/2956645 Mar 03 16:56:19 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2945409 Mar 03 16:56:19 rivo volumio[3305]: info: Using cached record of: qobuz://song/2945409 Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::stop Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:19 rivo volumio[3305]: info: CoreCommandRouter::volumioPushQueue Mar 03 16:56:19 rivo volumio[3305]: info: CorePlayQueue::saveQueue Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::play index undefined Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::updateTrackBlock Mar 03 16:56:19 rivo volumio[3305]: info: CorePlayQueue::getTrackBlock Mar 03 16:56:19 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:19 rivo volumio[3305]: info: CoreStateMachine::startPlaybackTimer Mar 03 16:56:19 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:19 rivo volumio[3305]: info: [1741017379028] ControllerQobuz::clearAddPlayTrack Mar 03 16:56:19 rivo volumio[3305]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 16:56:19 rivo volumio[3305]: info: Updating RAAT Signal Path Mar 03 16:56:30 rivo volumio[3305]: info: Preload queue cleared Mar 03 16:56:30 rivo volumio[3305]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::ClearQueue Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::stop Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::clearPlayQueue Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::saveQueue Mar 03 16:56:30 rivo volumio[3305]: info: CoreCommandRouter::volumioPushQueue Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::addQueueItems Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::addQueueItems Mar 03 16:56:30 rivo volumio[3305]: info: Preload queue cleared Mar 03 16:56:30 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/174306506 Mar 03 16:56:30 rivo volumio[3305]: info: Using cached record of: qobuz://song/174306506 Mar 03 16:56:30 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/5034555 Mar 03 16:56:30 rivo volumio[3305]: info: Using cached record of: qobuz://song/5034555 Mar 03 16:56:30 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/65170792 Mar 03 16:56:30 rivo volumio[3305]: info: Using cached record of: qobuz://song/65170792 Mar 03 16:56:30 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2879009 Mar 03 16:56:30 rivo volumio[3305]: info: Using cached record of: qobuz://song/2879009 Mar 03 16:56:30 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2878995 Mar 03 16:56:30 rivo volumio[3305]: info: Using cached record of: qobuz://song/2878995 Mar 03 16:56:30 rivo volumio[3305]: info: CoreCommandRouter::volumioPushQueue Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::saveQueue Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::updateTrackBlock Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::getTrackBlock Mar 03 16:56:30 rivo volumio[3305]: info: CoreCommandRouter::volumioPlay Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::play index 4 Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::addQueueItems Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::addQueueItems Mar 03 16:56:30 rivo volumio[3305]: info: Preload queue cleared Mar 03 16:56:30 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2850739 Mar 03 16:56:30 rivo volumio[3305]: info: Using cached record of: qobuz://song/2850739 Mar 03 16:56:30 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2956645 Mar 03 16:56:30 rivo volumio[3305]: info: Using cached record of: qobuz://song/2956645 Mar 03 16:56:30 rivo volumio[3305]: info: Adding Item to queue: qobuz://song/2945409 Mar 03 16:56:30 rivo volumio[3305]: info: Using cached record of: qobuz://song/2945409 Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::stop Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:30 rivo volumio[3305]: info: CoreCommandRouter::volumioPushQueue Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::saveQueue Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::play index undefined Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::updateTrackBlock Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::getTrackBlock Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:30 rivo volumio[3305]: info: CoreStateMachine::startPlaybackTimer Mar 03 16:56:30 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:30 rivo volumio[3305]: info: [1741017390981] ControllerQobuz::clearAddPlayTrack Mar 03 16:56:30 rivo volumio[3305]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 16:56:34 rivo volumio[3305]: 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/133.0.6943.121 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::volumioGetState Mar 03 16:56:34 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:34 rivo volumio[3305]: info: Received Get System Info Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 16:56:34 rivo volumio[3305]: info: Discovery: Getting this device information Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::volumioGetState Mar 03 16:56:34 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::volumioGetState Mar 03 16:56:34 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::volumioGetState Mar 03 16:56:34 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::volumioGetState Mar 03 16:56:34 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::volumioGetQueue Mar 03 16:56:34 rivo volumio[3305]: info: CoreStateMachine::getQueue Mar 03 16:56:34 rivo volumio[3305]: info: CorePlayQueue::getQueue Mar 03 16:56:34 rivo volumio[3305]: info: Listing playlists Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 03 16:56:34 rivo volumio[3305]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 03 16:56:34 rivo volumio[3305]: info: Received Get System Info Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 16:56:34 rivo volumio[3305]: info: Discovery: Getting this device information Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::volumioGetState Mar 03 16:56:34 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::volumioGetState Mar 03 16:56:34 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:34 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 03 16:56:37 rivo volumio[3305]: info: CoreCommandRouter::volumioPlay Mar 03 16:56:37 rivo volumio[3305]: info: CoreStateMachine::play index undefined Mar 03 16:56:37 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:37 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:37 rivo volumio[3305]: info: CoreStateMachine::startPlaybackTimer Mar 03 16:56:37 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:37 rivo volumio[3305]: info: [1741017397166] ControllerQobuz::clearAddPlayTrack Mar 03 16:56:37 rivo volumio[3305]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 16:56:44 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 03 16:56:54 rivo volumio[3305]: info: CoreCommandRouter::volumioPlay Mar 03 16:56:54 rivo volumio[3305]: info: CoreStateMachine::play index undefined Mar 03 16:56:54 rivo volumio[3305]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 16:56:54 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:54 rivo volumio[3305]: info: CoreStateMachine::startPlaybackTimer Mar 03 16:56:54 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:54 rivo volumio[3305]: info: [1741017414473] ControllerQobuz::clearAddPlayTrack Mar 03 16:56:54 rivo volumio[3305]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 16:56:57 rivo volumio[3305]: info: CoreCommandRouter::volumioGetState Mar 03 16:56:57 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:56:59 rivo volumio[3305]: info: Executing endpoint metavolumio Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 16:56:59 rivo volumio[3305]: info: Executing endpoint metavolumio Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 16:56:59 rivo volumio[3305]: info: Executing endpoint metavolumio Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:56:59 rivo volumio[3305]: info: Retrieving Cloud Streaming UI Mar 03 16:56:59 rivo volumio[3305]: info: Getting Tidal Cloud Configuration Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:56:59 rivo volumio[3305]: info: Getting Qobuz Cloud Configuration Mar 03 16:56:59 rivo volumio[3305]: info: Asking plugin for UI Config Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:56:59 rivo volumio[3305]: info: Getting Spotify Cloud Configuration Mar 03 16:56:59 rivo volumio[3305]: info: Asking plugin for UI Config Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:56:59 rivo volumio[3305]: info: Saving Spotify Acccount Mar 03 16:56:59 rivo volumio[3305]: info: Got it Mar 03 16:56:59 rivo volumio[3305]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 03 16:56:59 rivo volumio[3305]: info: Got Tidal Cloud Configuration Mar 03 16:56:59 rivo volumio[3305]: info: Got it Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 16:56:59 rivo volumio[3305]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 16:57:00 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 16:57:00 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 03 16:57:03 rivo volumio[3305]: info: Disabling MyMusic plugin bluetooth Mar 03 16:57:03 rivo sudo[7095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Mar 03 16:57:03 rivo sudo[7095]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:03 rivo systemd[1]: Stopping Volumio Bluetooth Module... Mar 03 16:57:03 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:03 rivo volumiobt[7097]: Killing pulse Mar 03 16:57:03 rivo volumiobt[7097]: Killing BT Pair agent Mar 03 16:57:03 rivo bluetoothd[3207]: Endpoint unregistered: sender=:1.43 path=/MediaEndpoint/A2DPSource Mar 03 16:57:03 rivo bluetoothd[3207]: Endpoint unregistered: sender=:1.43 path=/MediaEndpoint/A2DPSink Mar 03 16:57:03 rivo systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Mar 03 16:57:03 rivo systemd[1]: volumiobt.service: Succeeded. Mar 03 16:57:03 rivo systemd[1]: Stopped Volumio Bluetooth Module. Mar 03 16:57:03 rivo sudo[7095]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:03 rivo volumio[3305]: info: Volumio BT Module successfully stopped Mar 03 16:57:03 rivo volumio[3305]: info: Disabling plugin bluetooth Mar 03 16:57:03 rivo volumio[3305]: info: Done. Mar 03 16:57:04 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 16:57:04 rivo volumio[3305]: info: Enabling MyMusic plugin bluetooth Mar 03 16:57:04 rivo volumio[3305]: info: Enabling plugin bluetooth Mar 03 16:57:04 rivo volumio[3305]: info: Loading plugin "bluetooth"... Mar 03 16:57:04 rivo volumio[3305]: info: [1741017424303] Starting BluetoothController Mar 03 16:57:04 rivo volumio[3305]: info: PLUGIN START: bluetooth Mar 03 16:57:04 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 16:57:04 rivo volumio[3305]: info: Done. Mar 03 16:57:04 rivo sudo[7102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Mar 03 16:57:04 rivo sudo[7102]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:04 rivo sudo[7102]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:04 rivo volumio[3305]: info: Bluetooth name changed to Rivo Mar 03 16:57:05 rivo volumio[3305]: info: Disabling MyMusic plugin multiroom Mar 03 16:57:05 rivo volumio[3305]: info: Disabling plugin multiroom Mar 03 16:57:05 rivo volumio[3305]: info: Preparing to generate the ALSA configuration file Mar 03 16:57:05 rivo volumio[3305]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 03 16:57:05 rivo volumio[3305]: info: Reading ALSA contributions from plugins. Mar 03 16:57:05 rivo volumio[3305]: info: Asound.conf file written Mar 03 16:57:05 rivo sudo[7105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 03 16:57:05 rivo sudo[7105]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:05 rivo sudo[7105]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:05 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Mar 03 16:57:05 rivo kernel: Err: can not set rate to 4278967296! Mar 03 16:57:05 rivo kernel: Range[3920000 - 500000000] Mar 03 16:57:05 rivo kernel: out of value, fixed it Mar 03 16:57:05 rivo kernel: id=0 set inskew=0 Mar 03 16:57:05 rivo volumio[3305]: No protocol specified Mar 03 16:57:05 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:05 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Mar 03 16:57:05 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Mar 03 16:57:05 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Mar 03 16:57:05 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Mar 03 16:57:05 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Mar 03 16:57:05 rivo volumio[3305]: info: Output device has changed, restarting MPD Mar 03 16:57:05 rivo sudo[7110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 16:57:05 rivo sudo[7110]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:05 rivo volumio[3305]: info: Output device has changed, restarting Shairport Sync Mar 03 16:57:05 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:05 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:05 rivo sudo[7110]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:05 rivo sudo[7113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 16:57:05 rivo sudo[7113]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:05 rivo volumio[3305]: No protocol specified Mar 03 16:57:05 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:05 rivo volumio[3305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 16:57:05 rivo volumio[3305]: No protocol specified Mar 03 16:57:05 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:05 rivo volumio[3305]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:57:05 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:05 rivo volumio[3305]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 03 16:57:05 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:05 rivo vtcs[4068]: [2025-03-03 16:57:05.955] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 03 16:57:05 rivo vtcs[4068]: [2025-03-03 16:57:05.956] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Mar 03 16:57:05 rivo vtcs[4068]: [2025-03-03 16:57:05.956] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 03 16:57:05 rivo volumio[3305]: info: Done. Mar 03 16:57:06 rivo sudo[7122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:06 rivo sudo[7122]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:06 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Mar 03 16:57:06 rivo volumio[3305]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 03 16:57:06 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 03 16:57:06 rivo volumio[3305]: info: MPD Permissions set Mar 03 16:57:06 rivo systemd[1]: vtcs.service: Succeeded. Mar 03 16:57:06 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Mar 03 16:57:06 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:06 rivo sudo[7125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:06 rivo sudo[7125]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo sudo[7122]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo sudo[7125]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: Starting Shairport Sync Mar 03 16:57:06 rivo volumio[3305]: info: Preparing to generate the ALSA configuration file Mar 03 16:57:06 rivo sudo[7138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 16:57:06 rivo sudo[7138]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo volumio[3305]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 03 16:57:06 rivo volumio[3305]: info: Reading ALSA contributions from plugins. Mar 03 16:57:06 rivo volumio[3305]: info: Asound.conf file unchanged, so no further update is needed Mar 03 16:57:06 rivo volumio[3305]: info: Output device has changed, restarting MPD Mar 03 16:57:06 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 16:57:06 rivo systemd[1]: shairport-sync.service: Succeeded. Mar 03 16:57:06 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 16:57:06 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 16:57:06 rivo sudo[7138]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo sudo[7141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 16:57:06 rivo sudo[7141]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo volumio[3305]: info: Output device has changed, restarting Shairport Sync Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:06 rivo sudo[7141]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo sudo[7147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 16:57:06 rivo sudo[7147]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo volumio[3305]: No protocol specified Mar 03 16:57:06 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:06 rivo volumio[3305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 16:57:06 rivo volumio[3305]: No protocol specified Mar 03 16:57:06 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:06 rivo volumio[3305]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo sudo[7163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:06 rivo sudo[7163]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo sudo[7163]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo sudo[7167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:06 rivo sudo[7167]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: Not Reporting Auto name since its the default one Mar 03 16:57:06 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:06 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:06 rivo sudo[7167]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:06 rivo volumio[3305]: info: Enabling MyMusic plugin multiroom Mar 03 16:57:06 rivo volumio[3305]: info: Enabling plugin multiroom Mar 03 16:57:06 rivo volumio[3305]: info: Loading plugin "multiroom"... Mar 03 16:57:06 rivo volumio[3305]: info: Applying required configuration parameters for plugin multiroom Mar 03 16:57:06 rivo sudo[7176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 03 16:57:06 rivo sudo[7176]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo sudo[7176]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo volumio[3305]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Mar 03 16:57:06 rivo volumio[3305]: info: MRS: MultiRoom plugin initialized Mar 03 16:57:06 rivo volumio[3305]: info: MRS: STOPPING SNAPCLIENT Mar 03 16:57:06 rivo volumio[3305]: info: MRS: Snap server stop Mar 03 16:57:06 rivo sudo[7192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 03 16:57:06 rivo sudo[7192]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo volumio[3305]: info: MRS: STOPPING volumioStreaming Mar 03 16:57:06 rivo sudo[7195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 03 16:57:06 rivo sudo[7195]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo sudo[7192]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo sudo[7198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 03 16:57:06 rivo sudo[7198]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo sudo[7195]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo volumio[3305]: info: Preparing to generate the ALSA configuration file Mar 03 16:57:06 rivo sudo[7198]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo sudo[7202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 03 16:57:06 rivo sudo[7202]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:06 rivo sudo[7202]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:06 rivo volumio[3305]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 03 16:57:06 rivo volumio[3305]: info: MPD Permissions set Mar 03 16:57:06 rivo volumio[3305]: info: Shairport-Sync Started Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:57:06 rivo volumio[3305]: info: Not Reporting Auto name since its the default one Mar 03 16:57:06 rivo volumio[3305]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 03 16:57:06 rivo volumio[3305]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 03 16:57:06 rivo volumio[3305]: info: Reading ALSA contributions from plugins. Mar 03 16:57:07 rivo volumio[3305]: info: MRS: Removed streaming files Mar 03 16:57:07 rivo volumio[3305]: info: MRS: volumioStreaming STOPPED Mar 03 16:57:07 rivo volumio[3305]: info: MRS: SNAPSERVER STOPPED Mar 03 16:57:07 rivo volumio[3305]: info: MRS: SNAPCLIENT STOPPED Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 03 16:57:07 rivo volumio[3305]: No protocol specified Mar 03 16:57:07 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 03 16:57:07 rivo sudo[7217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 03 16:57:07 rivo sudo[7217]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo sudo[7217]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo sudo[7221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 03 16:57:07 rivo sudo[7221]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo volumio[3305]: No protocol specified Mar 03 16:57:07 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:07 rivo systemd[1]: Stopping RAAT DAEMON... Mar 03 16:57:07 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 03 16:57:07 rivo systemd[1]: raat-daemon.service: Succeeded. Mar 03 16:57:07 rivo systemd[1]: Stopped RAAT DAEMON. Mar 03 16:57:07 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:07 rivo volumio[3305]: info: Starting Shairport Sync Mar 03 16:57:07 rivo systemd[1]: Started RAAT DAEMON. Mar 03 16:57:07 rivo sudo[7221]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo sudo[7224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 03 16:57:07 rivo sudo[7224]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo sudo[7224]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:07 rivo sudo[7229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 03 16:57:07 rivo sudo[7229]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo sudo[7230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 16:57:07 rivo sudo[7230]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo volumio[3305]: info: Raat Daemon started successfully Mar 03 16:57:07 rivo sudo[7239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 03 16:57:07 rivo sudo[7239]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 16:57:07 rivo systemd[1]: Stopping RAAT DAEMON... Mar 03 16:57:07 rivo systemd[1]: shairport-sync.service: Succeeded. Mar 03 16:57:07 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 16:57:07 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 03 16:57:07 rivo systemd[1]: raat-daemon.service: Succeeded. Mar 03 16:57:07 rivo systemd[1]: Stopped RAAT DAEMON. Mar 03 16:57:07 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:07 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:07 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:07 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:07 rivo volumio[3305]: info: Asound.conf file written Mar 03 16:57:07 rivo systemd[1]: Started RAAT DAEMON. Mar 03 16:57:07 rivo sudo[7229]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 16:57:07 rivo sudo[7230]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo sudo[7246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 03 16:57:07 rivo systemd[1]: Started Volumio Bluetooth Module. Mar 03 16:57:07 rivo sudo[7246]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo sudo[7239]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo sudo[7246]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo sudo[7263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 03 16:57:07 rivo sudo[7263]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo volumiobt[7257]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 03 16:57:07 rivo sudo[7263]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Mar 03 16:57:07 rivo kernel: Err: can not set rate to 4274967296! Mar 03 16:57:07 rivo kernel: Range[3920000 - 500000000] Mar 03 16:57:07 rivo kernel: out of value, fixed it Mar 03 16:57:07 rivo kernel: id=0 set inskew=0 Mar 03 16:57:07 rivo volumio[3305]: No protocol specified Mar 03 16:57:07 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:07 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Mar 03 16:57:07 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Mar 03 16:57:07 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Mar 03 16:57:07 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Mar 03 16:57:07 rivo volumio[3305]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Mar 03 16:57:07 rivo volumio[3305]: info: Output device has changed, restarting MPD Mar 03 16:57:07 rivo sudo[7272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 16:57:07 rivo volumio[3305]: info: Output device has changed, restarting Shairport Sync Mar 03 16:57:07 rivo sudo[7272]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:07 rivo sudo[7272]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo sudo[7274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 16:57:07 rivo sudo[7274]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo volumio[3305]: No protocol specified Mar 03 16:57:07 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:07 rivo volumio[3305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 16:57:07 rivo volumio[3305]: No protocol specified Mar 03 16:57:07 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:07 rivo volumio[3305]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo volumio[3305]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:07 rivo sudo[7284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:07 rivo sudo[7284]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:07 rivo volumio[3305]: info: PLUGIN START: multiroom Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 03 16:57:07 rivo volumio[3305]: info: MRS: Pushing multiroomSync output for this device Mar 03 16:57:07 rivo volumio[3305]: info: MRS: Pushing multiroomSync output Mar 03 16:57:07 rivo volumio[3305]: info: Adding audio output: Mar 03 16:57:07 rivo volumio[3305]: error: Can't add: Mar 03 16:57:07 rivo volumio[3305]: info: Adding audio output: Mar 03 16:57:07 rivo volumio[3305]: error: Can't add: Mar 03 16:57:07 rivo sudo[7284]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:07 rivo volumio[3305]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:57:07 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:08 rivo sudo[7294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:08 rivo sudo[7294]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:08 rivo sudo[7294]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:08 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 16:57:08 rivo volumio[3305]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 03 16:57:08 rivo volumio[3305]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 03 16:57:08 rivo volumio[3305]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 03 16:57:08 rivo volumio[3305]: info: MPD Permissions set Mar 03 16:57:08 rivo volumio[3305]: info: Volumio BT Module successfully started Mar 03 16:57:08 rivo volumio[3305]: info: Shairport-Sync Started Mar 03 16:57:08 rivo volumio[3305]: info: Raat Daemon started successfully Mar 03 16:57:08 rivo volumio[3305]: info: Executing endpoint restartRAATSocket Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:57:08 rivo volumio[3305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 03 16:57:08 rivo volumio[3305]: info: Starting Shairport Sync Mar 03 16:57:08 rivo volumio[3305]: info: Preparing to generate the ALSA configuration file Mar 03 16:57:08 rivo sudo[7305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 16:57:08 rivo sudo[7305]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:08 rivo volumio[3305]: info: Done. Mar 03 16:57:08 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 16:57:08 rivo systemd[1]: shairport-sync.service: Succeeded. Mar 03 16:57:08 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 16:57:08 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 16:57:08 rivo sudo[7305]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: Not Reporting Auto name since its the default one Mar 03 16:57:08 rivo volumio[3305]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 03 16:57:08 rivo volumio[3305]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 03 16:57:08 rivo volumio[3305]: info: Reading ALSA contributions from plugins. Mar 03 16:57:08 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:08 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:08 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:08 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:08 rivo volumio[3305]: info: Shairport-Sync Started Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 03 16:57:08 rivo volumio[3305]: No protocol specified Mar 03 16:57:08 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:08 rivo volumio[3305]: info: Asound.conf file unchanged, so no further update is needed Mar 03 16:57:08 rivo volumio[3305]: info: Output device has changed, restarting MPD Mar 03 16:57:08 rivo sudo[7317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 03 16:57:08 rivo sudo[7317]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:08 rivo sudo[7317]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:08 rivo volumio[3305]: info: Output device has changed, restarting Shairport Sync Mar 03 16:57:08 rivo sudo[7320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 16:57:08 rivo sudo[7320]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:08 rivo sudo[7322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 03 16:57:08 rivo sudo[7322]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:08 rivo sudo[7320]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:08 rivo sudo[7323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 16:57:08 rivo sudo[7323]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:08 rivo systemd[1]: Stopping RAAT DAEMON... Mar 03 16:57:08 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 03 16:57:08 rivo systemd[1]: raat-daemon.service: Succeeded. Mar 03 16:57:08 rivo systemd[1]: Stopped RAAT DAEMON. Mar 03 16:57:08 rivo sudo[7327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 03 16:57:08 rivo sudo[7327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:08 rivo volumiobt[7257]: pulseaudio: no process found Mar 03 16:57:08 rivo sudo[7327]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:08 rivo volumio[3305]: No protocol specified Mar 03 16:57:08 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:08 rivo volumio[3305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 16:57:08 rivo volumio[3305]: No protocol specified Mar 03 16:57:08 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:08 rivo volumio[3305]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo systemd[1]: Started RAAT DAEMON. Mar 03 16:57:08 rivo sudo[7322]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:08 rivo volumio[3305]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo volumio[3305]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:57:08 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:08 rivo sudo[7349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:08 rivo sudo[7349]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:08 rivo volumio[3305]: info: Preparing to generate the ALSA configuration file Mar 03 16:57:08 rivo sudo[7349]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:08 rivo volumio[3305]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 03 16:57:08 rivo volumio[3305]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 03 16:57:08 rivo volumio[3305]: info: Reading ALSA contributions from plugins. Mar 03 16:57:08 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:08 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:08 rivo sudo[7356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:08 rivo sudo[7356]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:09 rivo sudo[7356]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:09 rivo volumio[3305]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 03 16:57:09 rivo volumio[3305]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 03 16:57:09 rivo volumio[3305]: info: MPD Permissions set Mar 03 16:57:09 rivo volumio[3305]: info: Raat Daemon started successfully Mar 03 16:57:09 rivo volumio[3305]: info: Executing endpoint restartRAATSocket Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 03 16:57:09 rivo volumio[3305]: info: TidalConnect service stoped! Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 16:57:09 rivo pulseaudio[7339]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 03 16:57:09 rivo pulseaudio[7339]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 03 16:57:09 rivo bluetoothd[3207]: Endpoint registered: sender=:1.71 path=/MediaEndpoint/A2DPSource Mar 03 16:57:09 rivo volumiobt[7257]: Applying permissions Mar 03 16:57:09 rivo volumiobt[7257]: Setting BT discoverable and pairable Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo bluetoothd[3207]: Endpoint registered: sender=:1.71 path=/MediaEndpoint/A2DPSink Mar 03 16:57:09 rivo pulseaudio[7339]: 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 Mar 03 16:57:09 rivo volumio[3305]: info: CorePlayQueue::getTrack 4 Mar 03 16:57:09 rivo volumio[3305]: info: TidalConnect service stoped! Mar 03 16:57:09 rivo volumiobt[7257]: Agent registered Mar 03 16:57:09 rivo volumiobt[7257]: [bluetooth]# -e power on Mar 03 16:57:09 rivo volumiobt[7257]: Invalid command in menu main: -e Mar 03 16:57:09 rivo volumiobt[7257]: Mar 03 16:57:09 rivo volumiobt[7257]: Use "help" for a list of available commands in a menu. Mar 03 16:57:09 rivo volumiobt[7257]: Use "menu " if you want to enter any submenu. Mar 03 16:57:09 rivo volumiobt[7257]: Use "back" if you want to return to menu main. Mar 03 16:57:09 rivo volumiobt[7257]: [bluetooth]# agent on Mar 03 16:57:09 rivo volumiobt[7257]: Agent is already registered Mar 03 16:57:09 rivo volumiobt[7257]: [bluetooth]# discoverable on Mar 03 16:57:09 rivo volumiobt[7257]: [bluetooth]# pairable on Mar 03 16:57:09 rivo volumiobt[7257]: [bluetooth]# agent NoInputNoOutput Mar 03 16:57:09 rivo volumiobt[7257]: Agent is already registered Mar 03 16:57:09 rivo volumiobt[7257]: [bluetooth]# default-agent Mar 03 16:57:09 rivo volumiobt[7257]: [bluetooth]# quit Mar 03 16:57:09 rivo volumiobt[7257]: [59B blob data] Mar 03 16:57:09 rivo volumio[3305]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 03 16:57:09 rivo volumio[3305]: info: Starting Shairport Sync Mar 03 16:57:09 rivo sudo[7375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 16:57:09 rivo sudo[7375]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:09 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 16:57:09 rivo systemd[1]: shairport-sync.service: Succeeded. Mar 03 16:57:09 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 16:57:09 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: Not Reporting Auto name since its the default one Mar 03 16:57:09 rivo sudo[7375]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:09 rivo volumio[3305]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 03 16:57:09 rivo volumio[3305]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 03 16:57:09 rivo volumio[3305]: info: Asound.conf file unchanged, so no further update is needed Mar 03 16:57:09 rivo volumio[3305]: info: Output device has changed, restarting MPD Mar 03 16:57:09 rivo sudo[7388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 03 16:57:09 rivo sudo[7388]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:09 rivo volumio[3305]: info: Output device has changed, restarting Shairport Sync Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:09 rivo sudo[7392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 16:57:09 rivo sudo[7392]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:09 rivo systemd[1]: Started Volumio Tidal Connect Service. Mar 03 16:57:09 rivo sudo[7388]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:09 rivo sudo[7392]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:09 rivo sudo[7396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 16:57:09 rivo sudo[7396]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:09 rivo volumio[3305]: No protocol specified Mar 03 16:57:09 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:09 rivo volumio[3305]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 16:57:09 rivo volumio[3305]: No protocol specified Mar 03 16:57:09 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:09 rivo volumio[3305]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo volumio[3305]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:09 rivo sudo[7406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:09 rivo sudo[7406]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:09 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Mar 03 16:57:09 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 03 16:57:09 rivo systemd[1]: vtcs.service: Succeeded. Mar 03 16:57:09 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Mar 03 16:57:09 rivo sudo[7406]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:09 rivo sudo[7413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 16:57:09 rivo sudo[7413]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:09 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:09 rivo volumio[3305]: info: Shairport-Sync Started Mar 03 16:57:09 rivo volumio[3305]: info: Disabling MyMusic plugin upnp Mar 03 16:57:09 rivo sudo[7413]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:09 rivo volumio[3305]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 03 16:57:09 rivo volumio[3305]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 03 16:57:09 rivo volumio[3305]: info: MPD Permissions set Mar 03 16:57:09 rivo volumio[3305]: info: TidalConnect service stoped! Mar 03 16:57:09 rivo sudo[7418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 03 16:57:09 rivo sudo[7418]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:09 rivo volumio[3305]: info: TidalConnect service stoped! Mar 03 16:57:09 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 03 16:57:09 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 03 16:57:10 rivo volumio[3305]: No protocol specified Mar 03 16:57:10 rivo volumio[3305]: xcb_connection_has_error() returned true Mar 03 16:57:10 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:10 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:10 rivo sudo[7429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 03 16:57:10 rivo sudo[7429]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:10 rivo sudo[7429]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo sudo[7435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 03 16:57:10 rivo sudo[7435]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:10 rivo systemd[1]: Stopping RAAT DAEMON... Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 16:57:10 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 03 16:57:10 rivo systemd[1]: raat-daemon.service: Succeeded. Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 16:57:10 rivo systemd[1]: Stopped RAAT DAEMON. Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo systemd[1]: Started RAAT DAEMON. Mar 03 16:57:10 rivo sudo[7435]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 16:57:10 rivo volumio[3305]: info: Not Reporting Auto name since its the default one Mar 03 16:57:10 rivo volumio[3305]: info: RAAT: Requesting Headphone Status Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 03 16:57:10 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:10 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:10 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 16:57:10 rivo volumio[3305]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 16:57:10 rivo volumio[3305]: info: Raat Daemon started successfully Mar 03 16:57:10 rivo volumio[3305]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 03 16:57:10 rivo volumio[3305]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 03 16:57:10 rivo volumio[3305]: info: RAAT: Requesting Headphone Status Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 03 16:57:10 rivo volumio[3305]: info: Enabling MyMusic plugin upnp Mar 03 16:57:10 rivo volumio[3305]: info: Enabling plugin upnp Mar 03 16:57:10 rivo volumio[3305]: info: Loading plugin "upnp"... Mar 03 16:57:10 rivo volumio[3305]: info: [1741017430366] Starting Upmpd Daemon Mar 03 16:57:10 rivo volumio[3305]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 16:57:10 rivo volumio[3305]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 16:57:10 rivo sudo[7460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 03 16:57:10 rivo sudo[7460]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 16:57:10 rivo volumio[3305]: Error: listen EADDRINUSE: address already in use :::6599 Mar 03 16:57:10 rivo volumio[3305]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Mar 03 16:57:10 rivo volumio[3305]: at listenInCluster (net.js:1379:12) Mar 03 16:57:10 rivo volumio[3305]: at Server.listen (net.js:1465:7) Mar 03 16:57:10 rivo volumio[3305]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 03 16:57:10 rivo volumio[3305]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 03 16:57:10 rivo volumio[3305]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 03 16:57:10 rivo volumio[3305]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 03 16:57:10 rivo volumio[3305]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Mar 03 16:57:10 rivo volumio[3305]: code: 'EADDRINUSE', Mar 03 16:57:10 rivo volumio[3305]: errno: -98, Mar 03 16:57:10 rivo volumio[3305]: syscall: 'listen', Mar 03 16:57:10 rivo volumio[3305]: address: '::', Mar 03 16:57:10 rivo volumio[3305]: port: 6599 Mar 03 16:57:10 rivo volumio[3305]: } Mar 03 16:57:10 rivo volumio[3305]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 16:57:10 rivo systemd[1]: Started Volumio Tidal Connect Service. Mar 03 16:57:10 rivo sudo[7460]: pam_unix(sudo:session): session closed for user root Mar 03 16:57:11 rivo sudo[7475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 16:56 Mar 03 16:57:11 rivo sudo[7475]: 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"