-- Logs begin at Mon 2024-12-23 00:23:42 CET, end at Thu 2025-02-27 14:07:22 CET. -- Feb 27 14:06:01 rivo volumio[3287]: info: explodeUri took 5664 milliseconds Feb 27 14:06:20 rivo volumio[3287]: info: Preload queue cleared Feb 27 14:06:20 rivo volumio[3287]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::ClearQueue Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::stop Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 14:06:20 rivo volumio[3287]: info: CorePlayQueue::clearPlayQueue Feb 27 14:06:20 rivo volumio[3287]: info: CorePlayQueue::saveQueue Feb 27 14:06:20 rivo volumio[3287]: info: CoreCommandRouter::volumioPushQueue Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::addQueueItems Feb 27 14:06:20 rivo volumio[3287]: info: CorePlayQueue::addQueueItems Feb 27 14:06:20 rivo volumio[3287]: info: Preload queue cleared Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201419 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201419 Feb 27 14:06:20 rivo volumio[3287]: info: CoreCommandRouter::volumioPushQueue Feb 27 14:06:20 rivo volumio[3287]: info: CorePlayQueue::saveQueue Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::updateTrackBlock Feb 27 14:06:20 rivo volumio[3287]: info: CorePlayQueue::getTrackBlock Feb 27 14:06:20 rivo volumio[3287]: info: CoreCommandRouter::volumioPlay Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::play index 0 Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::addQueueItems Feb 27 14:06:20 rivo volumio[3287]: info: CorePlayQueue::addQueueItems Feb 27 14:06:20 rivo volumio[3287]: info: Preload queue cleared Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201420 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201420 Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201421 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201421 Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201422 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201422 Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201423 Feb 27 14:06:20 rivo volumio[3287]: info: Exploding uri qobuz://song/243201423 in service qobuz Feb 27 14:06:20 rivo volumio[3287]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201424 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201424 Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201425 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201425 Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201426 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201426 Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201427 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201427 Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201428 Feb 27 14:06:20 rivo volumio[3287]: info: Exploding uri qobuz://song/243201428 in service qobuz Feb 27 14:06:20 rivo volumio[3287]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201429 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201429 Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201430 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201430 Feb 27 14:06:20 rivo volumio[3287]: info: Adding Item to queue: qobuz://song/243201431 Feb 27 14:06:20 rivo volumio[3287]: info: Using cached record of: qobuz://song/243201431 Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::stop Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::play index undefined Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 14:06:20 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:20 rivo volumio[3287]: info: CoreStateMachine::startPlaybackTimer Feb 27 14:06:20 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:20 rivo volumio[3287]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 14:06:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 14:06:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 27 14:06:20 rivo volumio[3287]: info: [1740661580410] ControllerQobuz::clearAddPlayTrack Feb 27 14:06:20 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand stop Feb 27 14:06:20 rivo volumio[3287]: info: sendMpdCommand stop took 18 milliseconds Feb 27 14:06:20 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand clear Feb 27 14:06:20 rivo volumio[3287]: info: Feb 27 14:06:20 rivo volumio[3287]: ---------------------------- MPD announces system playlist update Feb 27 14:06:20 rivo volumio[3287]: info: Ignoring MPD Status Update Feb 27 14:06:20 rivo volumio[3287]: info: sendMpdCommand clear took 5 milliseconds Feb 27 14:06:20 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/243201419" Feb 27 14:06:20 rivo volumio[3287]: error: updateQueue error: null Feb 27 14:06:20 rivo volumio[3287]: STREAMING PROXY: Handling url /?data=qobuz://song/243201419 Feb 27 14:06:20 rivo volumio[3287]: info: ------------------------------ 7ms Feb 27 14:06:20 rivo volumio[3287]: info: Executing endpoint getStreamUrlqobuz Feb 27 14:06:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 27 14:06:20 rivo volumio[3287]: info: explodeUri took 453 milliseconds Feb 27 14:06:20 rivo volumio[3287]: info: explodeUri took 595 milliseconds Feb 27 14:06:20 rivo volumio[3287]: info: CoreCommandRouter::volumioPushQueue Feb 27 14:06:20 rivo volumio[3287]: info: CorePlayQueue::saveQueue Feb 27 14:06:21 rivo volumio[3287]: info: CoreStateMachine::updateTrackBlock Feb 27 14:06:21 rivo volumio[3287]: info: CorePlayQueue::getTrackBlock Feb 27 14:06:21 rivo volumio[3287]: info: getStreamUrl took 629 milliseconds Feb 27 14:06:21 rivo volumio[3287]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3077903&eid=243201419&fmt=6&profile=raw&app_id=539451548&cid=2610646&etsp=1740665181&hmac=F2BZnDj502VkbL020V_yrpw0djc Feb 27 14:06:24 rivo volumio[3287]: STREAMING PROXY: Response: 200, length: 2559047 Feb 27 14:06:24 rivo volumio[3287]: STREAMING PROXY: Client dropped request, destroying Feb 27 14:06:24 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/243201419" Feb 27 14:06:24 rivo volumio[3287]: info: Feb 27 14:06:24 rivo volumio[3287]: ---------------------------- MPD announces system playlist update Feb 27 14:06:24 rivo volumio[3287]: info: Ignoring MPD Status Update Feb 27 14:06:24 rivo volumio[3287]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/243201419" took 3 milliseconds Feb 27 14:06:24 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 27 14:06:24 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand play Feb 27 14:06:24 rivo volumio[3287]: info: ------------------------------ 7ms Feb 27 14:06:24 rivo volumio[3287]: info: sendMpdCommand play took 5 milliseconds Feb 27 14:06:24 rivo volumio[3287]: STREAMING PROXY: Handling url /?data=qobuz://song/243201419 Feb 27 14:06:24 rivo volumio[3287]: info: Executing endpoint getStreamUrlqobuz Feb 27 14:06:24 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 27 14:06:25 rivo volumio[3287]: 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 Feb 27 14:06:25 rivo volumio[3287]: info: Received Get System Info Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 14:06:25 rivo volumio[3287]: info: Discovery: Getting this device information Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::volumioGetState Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::volumioGetState Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::volumioGetState Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::volumioGetQueue Feb 27 14:06:25 rivo volumio[3287]: info: CoreStateMachine::getQueue Feb 27 14:06:25 rivo volumio[3287]: info: CorePlayQueue::getQueue Feb 27 14:06:25 rivo volumio[3287]: info: Listing playlists Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 27 14:06:25 rivo volumio[3287]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 27 14:06:25 rivo volumio[3287]: info: Received Get System Info Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 14:06:25 rivo volumio[3287]: info: Discovery: Getting this device information Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::volumioGetState Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::volumioGetState Feb 27 14:06:25 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 27 14:06:28 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 14:06:28 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 14:06:28 rivo volumio[3287]: info: Discovery: Getting this device information Feb 27 14:06:28 rivo volumio[3287]: info: CoreCommandRouter::volumioGetState Feb 27 14:06:28 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 14:06:35 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 27 14:06:36 rivo volumio[3287]: info: CoreCommandRouter::volumioPlay Feb 27 14:06:36 rivo volumio[3287]: info: CoreStateMachine::play index undefined Feb 27 14:06:36 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 14:06:36 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:36 rivo volumio[3287]: info: CoreStateMachine::startPlaybackTimer Feb 27 14:06:36 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:36 rivo volumio[3287]: info: [1740661596536] ControllerQobuz::clearAddPlayTrack Feb 27 14:06:36 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand stop Feb 27 14:06:36 rivo volumio[3287]: info: Feb 27 14:06:36 rivo volumio[3287]: ---------------------------- MPD announces state update: player Feb 27 14:06:36 rivo volumio[3287]: info: sendMpdCommand stop took 3 milliseconds Feb 27 14:06:36 rivo volumio[3287]: info: ControllerMpd::getState Feb 27 14:06:36 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand status Feb 27 14:06:36 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand clear Feb 27 14:06:36 rivo volumio[3287]: info: Feb 27 14:06:36 rivo volumio[3287]: ---------------------------- MPD announces system playlist update Feb 27 14:06:36 rivo volumio[3287]: info: Ignoring MPD Status Update Feb 27 14:06:36 rivo volumio[3287]: info: sendMpdCommand status took 4 milliseconds Feb 27 14:06:36 rivo volumio[3287]: info: sendMpdCommand clear took 4 milliseconds Feb 27 14:06:36 rivo volumio[3287]: verbose: ControllerMpd::parseState Feb 27 14:06:36 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 14:06:36 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/243201419" Feb 27 14:06:36 rivo volumio[3287]: error: updateQueue error: null Feb 27 14:06:36 rivo volumio[3287]: info: ------------------------------ 9ms Feb 27 14:06:36 rivo volumio[3287]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 27 14:06:36 rivo volumio[3287]: STREAMING PROXY: Handling url /?data=qobuz://song/243201419 Feb 27 14:06:36 rivo volumio[3287]: verbose: ControllerMpd::parseTrackInfo Feb 27 14:06:36 rivo volumio[3287]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 27 14:06:36 rivo volumio[3287]: info: ------------------------------ 19ms Feb 27 14:06:36 rivo volumio[3287]: info: Executing endpoint getStreamUrlqobuz Feb 27 14:06:36 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 27 14:06:36 rivo volumio[3287]: info: getStreamUrl took 425 milliseconds Feb 27 14:06:36 rivo volumio[3287]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3077903&eid=243201419&fmt=6&profile=raw&app_id=539451548&cid=2610646&etsp=1740665197&hmac=S7Yh1zrCjDjY6h7bgkmnorjWmWo Feb 27 14:06:38 rivo volumio[3287]: info: CoreCommandRouter::volumioPlay Feb 27 14:06:38 rivo volumio[3287]: info: CoreStateMachine::play index undefined Feb 27 14:06:38 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 14:06:38 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:38 rivo volumio[3287]: info: CoreStateMachine::startPlaybackTimer Feb 27 14:06:38 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:38 rivo volumio[3287]: info: [1740661598148] ControllerQobuz::clearAddPlayTrack Feb 27 14:06:38 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand stop Feb 27 14:06:38 rivo volumio[3287]: STREAMING PROXY: Response: 200, length: 2559047 Feb 27 14:06:38 rivo volumio[3287]: STREAMING PROXY: Client dropped request, destroying Feb 27 14:06:38 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/243201419" Feb 27 14:06:38 rivo volumio[3287]: info: Feb 27 14:06:38 rivo volumio[3287]: ---------------------------- MPD announces system playlist update Feb 27 14:06:38 rivo volumio[3287]: info: Ignoring MPD Status Update Feb 27 14:06:38 rivo volumio[3287]: info: sendMpdCommand stop took 34 milliseconds Feb 27 14:06:38 rivo volumio[3287]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/243201419" took 3 milliseconds Feb 27 14:06:38 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand clear Feb 27 14:06:38 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 27 14:06:38 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand play Feb 27 14:06:38 rivo volumio[3287]: info: Feb 27 14:06:38 rivo volumio[3287]: ---------------------------- MPD announces system playlist update Feb 27 14:06:38 rivo volumio[3287]: info: Ignoring MPD Status Update Feb 27 14:06:38 rivo volumio[3287]: info: ------------------------------ 7ms Feb 27 14:06:38 rivo volumio[3287]: info: sendMpdCommand clear took 4 milliseconds Feb 27 14:06:38 rivo volumio[3287]: info: sendMpdCommand play took 5 milliseconds Feb 27 14:06:38 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/243201419" Feb 27 14:06:38 rivo volumio[3287]: STREAMING PROXY: Handling url /?data=qobuz://song/243201419 Feb 27 14:06:38 rivo volumio[3287]: error: updateQueue error: null Feb 27 14:06:38 rivo volumio[3287]: info: ------------------------------ 10ms Feb 27 14:06:38 rivo volumio[3287]: info: Executing endpoint getStreamUrlqobuz Feb 27 14:06:38 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Feb 27 14:06:46 rivo volumio[3287]: info: CoreCommandRouter::volumioPlay Feb 27 14:06:46 rivo volumio[3287]: info: CoreStateMachine::play index undefined Feb 27 14:06:46 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 14:06:46 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:46 rivo volumio[3287]: info: CoreStateMachine::startPlaybackTimer Feb 27 14:06:46 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:46 rivo volumio[3287]: info: [1740661606266] ControllerQobuz::clearAddPlayTrack Feb 27 14:06:46 rivo volumio[3287]: verbose: ControllerMpd::sendMpdCommand stop Feb 27 14:06:47 rivo volumio[3287]: info: Preload queue cleared Feb 27 14:06:47 rivo volumio[3287]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 27 14:06:47 rivo volumio[3287]: info: CoreStateMachine::ClearQueue Feb 27 14:06:47 rivo volumio[3287]: info: CoreStateMachine::stop Feb 27 14:06:47 rivo volumio[3287]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 14:06:47 rivo volumio[3287]: info: CorePlayQueue::clearPlayQueue Feb 27 14:06:47 rivo volumio[3287]: info: CorePlayQueue::saveQueue Feb 27 14:06:47 rivo volumio[3287]: info: CoreCommandRouter::volumioPushQueue Feb 27 14:06:47 rivo volumio[3287]: info: CoreStateMachine::addQueueItems Feb 27 14:06:47 rivo volumio[3287]: info: CorePlayQueue::addQueueItems Feb 27 14:06:47 rivo volumio[3287]: info: Preload queue cleared Feb 27 14:06:47 rivo volumio[3287]: info: Adding Item to queue: qobuz://album/swldrm5ra6teb Feb 27 14:06:47 rivo volumio[3287]: info: Exploding uri qobuz://album/swldrm5ra6teb in service qobuz Feb 27 14:06:47 rivo volumio[3287]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 27 14:06:47 rivo systemd[1]: Stopping Music Player Daemon... Feb 27 14:06:47 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:06:55 rivo volumio[3287]: info: CoreCommandRouter::volumioGetState Feb 27 14:06:55 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:57 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 14:06:57 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 14:06:57 rivo volumio[3287]: info: Discovery: Getting this device information Feb 27 14:06:57 rivo volumio[3287]: info: CoreCommandRouter::volumioGetState Feb 27 14:06:57 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:06:57 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 14:06:59 rivo volumio[3287]: info: Retrieving Cloud Streaming UI Feb 27 14:06:59 rivo volumio[3287]: info: Getting Tidal Cloud Configuration Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 14:06:59 rivo volumio[3287]: info: Getting Qobuz Cloud Configuration Feb 27 14:06:59 rivo volumio[3287]: info: Asking plugin for UI Config Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 14:06:59 rivo volumio[3287]: info: Getting Spotify Cloud Configuration Feb 27 14:06:59 rivo volumio[3287]: info: Asking plugin for UI Config Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 14:06:59 rivo volumio[3287]: info: Saving Spotify Acccount Feb 27 14:06:59 rivo volumio[3287]: info: Got it Feb 27 14:06:59 rivo volumio[3287]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 27 14:06:59 rivo volumio[3287]: info: Got Tidal Cloud Configuration Feb 27 14:06:59 rivo volumio[3287]: info: Got it Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::volumioGetBrowseSources Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::volumioGetBrowseSources Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::volumioGetBrowseSources Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 27 14:06:59 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 27 14:07:03 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 27 14:07:04 rivo volumio[3287]: info: Disabling MyMusic plugin bluetooth Feb 27 14:07:04 rivo sudo[4655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Feb 27 14:07:04 rivo sudo[4655]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:04 rivo systemd[1]: Stopping Volumio Bluetooth Module... Feb 27 14:07:04 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:04 rivo volumiobt[4657]: Killing pulse Feb 27 14:07:04 rivo bluetoothd[3210]: Endpoint unregistered: sender=:1.43 path=/MediaEndpoint/A2DPSource Feb 27 14:07:04 rivo bluetoothd[3210]: Endpoint unregistered: sender=:1.43 path=/MediaEndpoint/A2DPSink Feb 27 14:07:04 rivo volumiobt[4657]: Killing BT Pair agent Feb 27 14:07:04 rivo systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:04 rivo systemd[1]: volumiobt.service: Succeeded. Feb 27 14:07:04 rivo systemd[1]: Stopped Volumio Bluetooth Module. Feb 27 14:07:04 rivo sudo[4655]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:04 rivo volumio[3287]: info: Volumio BT Module successfully stopped Feb 27 14:07:04 rivo volumio[3287]: info: Disabling plugin bluetooth Feb 27 14:07:04 rivo volumio[3287]: info: Done. Feb 27 14:07:05 rivo volumio[3287]: info: Enabling MyMusic plugin bluetooth Feb 27 14:07:05 rivo volumio[3287]: info: Enabling plugin bluetooth Feb 27 14:07:05 rivo volumio[3287]: info: Loading plugin "bluetooth"... Feb 27 14:07:05 rivo volumio[3287]: info: [1740661625724] Starting BluetoothController Feb 27 14:07:05 rivo volumio[3287]: info: PLUGIN START: bluetooth Feb 27 14:07:05 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 27 14:07:05 rivo volumio[3287]: info: Done. Feb 27 14:07:05 rivo sudo[4661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Feb 27 14:07:05 rivo sudo[4661]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:05 rivo sudo[4661]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:05 rivo volumio[3287]: info: Bluetooth name changed to Rivo Feb 27 14:07:06 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:07:06 rivo volumio[3287]: info: CorePlayQueue::getTrack 1 Feb 27 14:07:07 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 27 14:07:08 rivo sudo[4681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 27 14:07:08 rivo sudo[4681]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:08 rivo systemd[1]: Started Volumio Bluetooth Module. Feb 27 14:07:08 rivo sudo[4681]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:08 rivo volumio[3287]: info: Volumio BT Module successfully started Feb 27 14:07:08 rivo sudo[4689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 27 14:07:08 rivo sudo[4689]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:08 rivo volumiobt[4683]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 27 14:07:08 rivo sudo[4689]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:09 rivo sudo[4697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Feb 27 14:07:09 rivo sudo[4697]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:09 rivo volumiobt[4683]: pulseaudio: no process found Feb 27 14:07:09 rivo sudo[4697]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:10 rivo pulseaudio[4701]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 27 14:07:10 rivo pulseaudio[4701]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 27 14:07:10 rivo volumiobt[4683]: Applying permissions Feb 27 14:07:10 rivo volumiobt[4683]: Setting BT discoverable and pairable Feb 27 14:07:10 rivo bluetoothd[3210]: Endpoint registered: sender=:1.67 path=/MediaEndpoint/A2DPSource Feb 27 14:07:10 rivo bluetoothd[3210]: Endpoint registered: sender=:1.67 path=/MediaEndpoint/A2DPSink Feb 27 14:07:10 rivo pulseaudio[4701]: 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 27 14:07:10 rivo volumiobt[4683]: Agent registered Feb 27 14:07:10 rivo volumiobt[4683]: [bluetooth]# -e power on Feb 27 14:07:10 rivo volumiobt[4683]: Invalid command in menu main: -e Feb 27 14:07:10 rivo volumiobt[4683]: Feb 27 14:07:10 rivo volumiobt[4683]: Use "help" for a list of available commands in a menu. Feb 27 14:07:10 rivo volumiobt[4683]: Use "menu " if you want to enter any submenu. Feb 27 14:07:10 rivo volumiobt[4683]: Use "back" if you want to return to menu main. Feb 27 14:07:10 rivo volumiobt[4683]: [bluetooth]# agent on Feb 27 14:07:10 rivo volumiobt[4683]: Agent is already registered Feb 27 14:07:10 rivo volumiobt[4683]: [bluetooth]# discoverable on Feb 27 14:07:10 rivo volumiobt[4683]: [bluetooth]# pairable on Feb 27 14:07:10 rivo volumiobt[4683]: [bluetooth]# agent NoInputNoOutput Feb 27 14:07:10 rivo volumiobt[4683]: Agent is already registered Feb 27 14:07:10 rivo volumiobt[4683]: [bluetooth]# default-agent Feb 27 14:07:10 rivo volumiobt[4683]: [bluetooth]# quit Feb 27 14:07:10 rivo volumiobt[4683]: [59B blob data] Feb 27 14:07:11 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 27 14:07:13 rivo volumio[3287]: info: Disabling MyMusic plugin multiroom Feb 27 14:07:13 rivo volumio[3287]: info: Disabling plugin multiroom Feb 27 14:07:13 rivo volumio[3287]: info: Preparing to generate the ALSA configuration file Feb 27 14:07:13 rivo volumio[3287]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 27 14:07:13 rivo volumio[3287]: info: Reading ALSA contributions from plugins. Feb 27 14:07:13 rivo volumio[3287]: info: Asound.conf file written Feb 27 14:07:13 rivo sudo[4707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 27 14:07:13 rivo sudo[4707]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:13 rivo sudo[4707]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:13 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 27 14:07:13 rivo kernel: Err: can not set rate to 4278967296! Feb 27 14:07:13 rivo kernel: Range[3920000 - 500000000] Feb 27 14:07:13 rivo kernel: out of value, fixed it Feb 27 14:07:13 rivo kernel: id=0 set inskew=0 Feb 27 14:07:13 rivo volumio[3287]: No protocol specified Feb 27 14:07:13 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:13 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 27 14:07:13 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 27 14:07:13 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 27 14:07:13 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 27 14:07:13 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 27 14:07:13 rivo volumio[3287]: info: Output device has changed, restarting MPD Feb 27 14:07:13 rivo volumio[3287]: info: Output device has changed, restarting Shairport Sync Feb 27 14:07:13 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:13 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:13 rivo sudo[4712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 14:07:13 rivo sudo[4712]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:13 rivo sudo[4712]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:13 rivo sudo[4714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 14:07:13 rivo sudo[4714]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:13 rivo volumio[3287]: No protocol specified Feb 27 14:07:13 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:13 rivo volumio[3287]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 14:07:13 rivo volumio[3287]: No protocol specified Feb 27 14:07:13 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:13 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:13 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:13 rivo volumio[3287]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 27 14:07:13 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:13 rivo vtcs[4045]: [2025-02-27 14:07:13.934] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Feb 27 14:07:13 rivo vtcs[4045]: [2025-02-27 14:07:13.935] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Feb 27 14:07:13 rivo vtcs[4045]: [2025-02-27 14:07:13.936] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Feb 27 14:07:13 rivo volumio[3287]: info: Done. Feb 27 14:07:13 rivo sudo[4724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:13 rivo sudo[4724]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:14 rivo volumio[3287]: info: MPD Permissions set Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo systemd[1]: vtcs.service: Succeeded. Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Feb 27 14:07:14 rivo sudo[4727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:14 rivo sudo[4727]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:14 rivo sudo[4724]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 27 14:07:14 rivo sudo[4727]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:14 rivo volumio[3287]: info: Starting Shairport Sync Feb 27 14:07:14 rivo sudo[4738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 14:07:14 rivo sudo[4738]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:14 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 14:07:14 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 27 14:07:14 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:14 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:14 rivo sudo[4738]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:14 rivo volumio[3287]: info: Shairport-Sync Started Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:07:14 rivo volumio[3287]: info: Not Reporting Auto name since its the default one Feb 27 14:07:14 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 27 14:07:14 rivo volumio[3287]: No protocol specified Feb 27 14:07:14 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:14 rivo sudo[4760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 27 14:07:14 rivo sudo[4760]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:14 rivo sudo[4760]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:14 rivo sudo[4762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 27 14:07:14 rivo sudo[4762]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:14 rivo systemd[1]: Stopping RAAT DAEMON... Feb 27 14:07:14 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:14 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 27 14:07:14 rivo systemd[1]: Stopped RAAT DAEMON. Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:14 rivo systemd[1]: Started RAAT DAEMON. Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:14 rivo sudo[4762]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:14 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:14 rivo volumio[3287]: info: Raat Daemon started successfully Feb 27 14:07:14 rivo volumio[3287]: info: Enabling MyMusic plugin multiroom Feb 27 14:07:14 rivo volumio[3287]: info: Enabling plugin multiroom Feb 27 14:07:14 rivo volumio[3287]: info: Loading plugin "multiroom"... Feb 27 14:07:14 rivo volumio[3287]: info: Applying required configuration parameters for plugin multiroom Feb 27 14:07:14 rivo sudo[4770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 27 14:07:14 rivo sudo[4770]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:14 rivo sudo[4770]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:14 rivo volumio[3287]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 27 14:07:14 rivo volumio[3287]: info: MRS: MultiRoom plugin initialized Feb 27 14:07:14 rivo volumio[3287]: info: MRS: STOPPING SNAPCLIENT Feb 27 14:07:14 rivo volumio[3287]: info: MRS: Snap server stop Feb 27 14:07:14 rivo sudo[4786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 27 14:07:14 rivo sudo[4786]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo volumio[3287]: info: MRS: STOPPING volumioStreaming Feb 27 14:07:15 rivo sudo[4789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 27 14:07:15 rivo sudo[4789]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo sudo[4786]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo sudo[4791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 27 14:07:15 rivo sudo[4791]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo volumio[3287]: info: Preparing to generate the ALSA configuration file Feb 27 14:07:15 rivo sudo[4789]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo sudo[4795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 27 14:07:15 rivo sudo[4795]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo sudo[4795]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo sudo[4791]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo volumio[3287]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 27 14:07:15 rivo volumio[3287]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 14:07:15 rivo volumio[3287]: info: Reading ALSA contributions from plugins. Feb 27 14:07:15 rivo volumio[3287]: info: MRS: Removed streaming files Feb 27 14:07:15 rivo volumio[3287]: info: MRS: volumioStreaming STOPPED Feb 27 14:07:15 rivo volumio[3287]: info: MRS: SNAPSERVER STOPPED Feb 27 14:07:15 rivo volumio[3287]: info: MRS: SNAPCLIENT STOPPED Feb 27 14:07:15 rivo volumio[3287]: info: Asound.conf file written Feb 27 14:07:15 rivo sudo[4798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 27 14:07:15 rivo sudo[4798]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo sudo[4798]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 27 14:07:15 rivo kernel: Err: can not set rate to 4274967296! Feb 27 14:07:15 rivo kernel: Range[3920000 - 500000000] Feb 27 14:07:15 rivo kernel: out of value, fixed it Feb 27 14:07:15 rivo kernel: id=0 set inskew=0 Feb 27 14:07:15 rivo volumio[3287]: No protocol specified Feb 27 14:07:15 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: info: Output device has changed, restarting MPD Feb 27 14:07:15 rivo sudo[4803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 14:07:15 rivo sudo[4803]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo volumio[3287]: info: Output device has changed, restarting Shairport Sync Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:15 rivo sudo[4803]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo sudo[4806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 14:07:15 rivo sudo[4806]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo volumio[3287]: No protocol specified Feb 27 14:07:15 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:15 rivo volumio[3287]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 14:07:15 rivo volumio[3287]: No protocol specified Feb 27 14:07:15 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:15 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo sudo[4815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:15 rivo sudo[4815]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo volumio[3287]: info: PLUGIN START: multiroom Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 27 14:07:15 rivo volumio[3287]: info: MRS: Pushing multiroomSync output for this device Feb 27 14:07:15 rivo volumio[3287]: info: MRS: Pushing multiroomSync output Feb 27 14:07:15 rivo volumio[3287]: info: Adding audio output: Feb 27 14:07:15 rivo volumio[3287]: error: Can't add: Feb 27 14:07:15 rivo volumio[3287]: info: Adding audio output: Feb 27 14:07:15 rivo volumio[3287]: error: Can't add: Feb 27 14:07:15 rivo sudo[4815]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:15 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:15 rivo sudo[4825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:15 rivo volumio[3287]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 27 14:07:15 rivo sudo[4825]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo volumio[3287]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 27 14:07:15 rivo volumio[3287]: info: MPD Permissions set Feb 27 14:07:15 rivo volumio[3287]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo sudo[4825]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:15 rivo volumio[3287]: info: Starting Shairport Sync Feb 27 14:07:15 rivo volumio[3287]: info: Preparing to generate the ALSA configuration file Feb 27 14:07:15 rivo sudo[4835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 14:07:15 rivo sudo[4835]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo volumio[3287]: info: Done. Feb 27 14:07:15 rivo volumio[3287]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 27 14:07:15 rivo volumio[3287]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 14:07:15 rivo volumio[3287]: info: Reading ALSA contributions from plugins. Feb 27 14:07:15 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 27 14:07:15 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 14:07:15 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 27 14:07:15 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:15 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:15 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:15 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:15 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:15 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:15 rivo volumio[3287]: info: Asound.conf file written Feb 27 14:07:15 rivo sudo[4835]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo sudo[4839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 27 14:07:15 rivo sudo[4839]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:15 rivo sudo[4839]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:15 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 27 14:07:15 rivo kernel: Err: can not set rate to 4270967296! Feb 27 14:07:15 rivo kernel: Range[3920000 - 500000000] Feb 27 14:07:15 rivo kernel: out of value, fixed it Feb 27 14:07:15 rivo kernel: id=0 set inskew=0 Feb 27 14:07:15 rivo volumio[3287]: No protocol specified Feb 27 14:07:15 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 27 14:07:15 rivo volumio[3287]: info: Output device has changed, restarting MPD Feb 27 14:07:16 rivo sudo[4850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 14:07:16 rivo volumio[3287]: info: Output device has changed, restarting Shairport Sync Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:16 rivo sudo[4850]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo sudo[4850]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:16 rivo sudo[4853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 14:07:16 rivo sudo[4853]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo volumio[3287]: No protocol specified Feb 27 14:07:16 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:16 rivo volumio[3287]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 14:07:16 rivo volumio[3287]: No protocol specified Feb 27 14:07:16 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:16 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo sudo[4862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:16 rivo sudo[4862]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo volumio[3287]: info: Preparing to generate the ALSA configuration file Feb 27 14:07:16 rivo sudo[4862]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:16 rivo sudo[4869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:16 rivo sudo[4869]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: Not Reporting Auto name since its the default one Feb 27 14:07:16 rivo volumio[3287]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 27 14:07:16 rivo volumio[3287]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 14:07:16 rivo volumio[3287]: info: Reading ALSA contributions from plugins. Feb 27 14:07:16 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:16 rivo sudo[4869]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:16 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:16 rivo volumio[3287]: info: MPD Permissions set Feb 27 14:07:16 rivo volumio[3287]: info: Shairport-Sync Started Feb 27 14:07:16 rivo volumio[3287]: info: Executing endpoint restartRAATSocket Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 27 14:07:16 rivo volumio[3287]: No protocol specified Feb 27 14:07:16 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:16 rivo volumio[3287]: info: CorePlayQueue::getTrack 0 Feb 27 14:07:16 rivo volumio[3287]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 27 14:07:16 rivo sudo[4886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 27 14:07:16 rivo sudo[4886]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo volumio[3287]: info: Starting Shairport Sync Feb 27 14:07:16 rivo sudo[4886]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:16 rivo sudo[4889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 27 14:07:16 rivo sudo[4889]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo sudo[4890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 14:07:16 rivo sudo[4890]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: Not Reporting Auto name since its the default one Feb 27 14:07:16 rivo volumio[3287]: info: Asound.conf file unchanged, so no further update is needed Feb 27 14:07:16 rivo volumio[3287]: info: Output device has changed, restarting MPD Feb 27 14:07:16 rivo systemd[1]: Stopping RAAT DAEMON... Feb 27 14:07:16 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:16 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 27 14:07:16 rivo systemd[1]: Stopped RAAT DAEMON. Feb 27 14:07:16 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 14:07:16 rivo sudo[4898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 14:07:16 rivo systemd[1]: Started RAAT DAEMON. Feb 27 14:07:16 rivo sudo[4898]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo sudo[4889]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:16 rivo volumio[3287]: info: Output device has changed, restarting Shairport Sync Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:16 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 27 14:07:16 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:16 rivo sudo[4898]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:16 rivo sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 14:07:16 rivo sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:16 rivo sudo[4890]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:16 rivo volumio[3287]: No protocol specified Feb 27 14:07:16 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:16 rivo volumio[3287]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 14:07:16 rivo volumio[3287]: No protocol specified Feb 27 14:07:16 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:16 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:16 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:16 rivo sudo[4917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:16 rivo sudo[4917]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:16 rivo volumio[3287]: info: Preparing to generate the ALSA configuration file Feb 27 14:07:17 rivo sudo[4917]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:17 rivo sudo[4924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:17 rivo sudo[4924]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:17 rivo volumio[3287]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 27 14:07:17 rivo volumio[3287]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 14:07:17 rivo volumio[3287]: info: Reading ALSA contributions from plugins. Feb 27 14:07:17 rivo sudo[4924]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:17 rivo volumio[3287]: info: MPD Permissions set Feb 27 14:07:17 rivo volumio[3287]: info: TidalConnect service stoped! Feb 27 14:07:17 rivo volumio[3287]: info: Shairport-Sync Started Feb 27 14:07:17 rivo volumio[3287]: info: Raat Daemon started successfully Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 27 14:07:17 rivo volumio[3287]: No protocol specified Feb 27 14:07:17 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:17 rivo volumio[3287]: info: TidalConnect service stoped! Feb 27 14:07:17 rivo sudo[4930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 27 14:07:17 rivo sudo[4930]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:17 rivo sudo[4930]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo sudo[4934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 27 14:07:17 rivo sudo[4934]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:17 rivo systemd[1]: Stopping RAAT DAEMON... Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 27 14:07:17 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:17 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 27 14:07:17 rivo systemd[1]: Stopped RAAT DAEMON. Feb 27 14:07:17 rivo systemd[1]: Started RAAT DAEMON. Feb 27 14:07:17 rivo sudo[4934]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:17 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:17 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:17 rivo volumio[3287]: info: Disabling MyMusic plugin upnp Feb 27 14:07:17 rivo volumio[3287]: info: Raat Daemon started successfully Feb 27 14:07:17 rivo sudo[4967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 27 14:07:18 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 27 14:07:18 rivo kernel: Err: can not set rate to 4266967296! Feb 27 14:07:18 rivo kernel: Range[3920000 - 500000000] Feb 27 14:07:18 rivo kernel: out of value, fixed it Feb 27 14:07:18 rivo kernel: id=0 set inskew=0 Feb 27 14:07:17 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: Not Reporting Auto name since its the default one Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo volumio[3287]: error: Upnp client error: Error: This socket has been ended by the other party Feb 27 14:07:18 rivo volumio[3287]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 27 14:07:18 rivo volumio[3287]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 27 14:07:18 rivo volumio[3287]: info: Starting Shairport Sync Feb 27 14:07:18 rivo volumio[3287]: info: RAAT: Requesting Headphone Status Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 27 14:07:18 rivo volumio[3287]: No protocol specified Feb 27 14:07:18 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:18 rivo volumio[3287]: info: Asound.conf file written Feb 27 14:07:18 rivo volumio[3287]: No protocol specified Feb 27 14:07:18 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:18 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 27 14:07:18 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 27 14:07:18 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 27 14:07:18 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 27 14:07:17 rivo sudo[4967]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo systemd[1]: Started Volumio Tidal Connect Service. Feb 27 14:07:18 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 27 14:07:18 rivo volumio[3287]: info: Output device has changed, restarting MPD Feb 27 14:07:18 rivo volumio[3287]: info: Output device has changed, restarting Shairport Sync Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: No protocol specified Feb 27 14:07:18 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:18 rivo volumio[3287]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 14:07:18 rivo volumio[3287]: No protocol specified Feb 27 14:07:18 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:18 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: Preparing to generate the ALSA configuration file Feb 27 14:07:18 rivo volumio[3287]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 27 14:07:18 rivo volumio[3287]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 14:07:18 rivo volumio[3287]: info: Reading ALSA contributions from plugins. Feb 27 14:07:18 rivo volumio[3287]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 27 14:07:18 rivo volumio[3287]: info: MPD Permissions set Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo sudo[4979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: Starting Shairport Sync Feb 27 14:07:18 rivo volumio[3287]: info: Asound.conf file unchanged, so no further update is needed Feb 27 14:07:18 rivo volumio[3287]: info: Output device has changed, restarting MPD Feb 27 14:07:18 rivo volumio[3287]: info: Output device has changed, restarting Shairport Sync Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: No protocol specified Feb 27 14:07:18 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:18 rivo volumio[3287]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 14:07:18 rivo volumio[3287]: No protocol specified Feb 27 14:07:18 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:18 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: Preparing to generate the ALSA configuration file Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo sudo[4979]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: Not Reporting Auto name since its the default one Feb 27 14:07:18 rivo volumio[3287]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 27 14:07:18 rivo volumio[3287]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 14:07:18 rivo volumio[3287]: info: Reading ALSA contributions from plugins. Feb 27 14:07:18 rivo volumio[3287]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 27 14:07:18 rivo volumio[3287]: info: MPD Permissions set Feb 27 14:07:18 rivo volumio[3287]: info: TidalConnect service stoped! Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:17 rivo sudo[4982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 27 14:07:17 rivo sudo[4982]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:17 rivo sudo[4987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 27 14:07:17 rivo sudo[4987]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:17 rivo sudo[4989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 27 14:07:17 rivo sudo[4989]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:17 rivo sudo[4989]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:17 rivo sudo[4995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 14:07:17 rivo sudo[4995]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:17 rivo sudo[4995]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:17 rivo sudo[4998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 14:07:17 rivo sudo[4998]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo sudo[5006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:18 rivo sudo[5006]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo sudo[5011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:18 rivo sudo[5011]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo sudo[5020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 14:07:18 rivo sudo[5020]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo sudo[5023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 14:07:18 rivo sudo[5023]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo sudo[5023]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:18 rivo sudo[5026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 14:07:18 rivo sudo[5026]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo sudo[5035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:18 rivo sudo[5035]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo sudo[5040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:18 rivo sudo[5040]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo sudo[4979]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:18 rivo sudo[4987]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:18 rivo volumio[3287]: No protocol specified Feb 27 14:07:18 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:18 rivo sudo[5064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 27 14:07:18 rivo sudo[5064]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo sudo[5067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 27 14:07:18 rivo sudo[5067]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo systemd[1]: Stopping RAAT DAEMON... Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Feb 27 14:07:18 rivo volumio[3287]: info: TidalConnect service stoped! Feb 27 14:07:18 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 14:07:18 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 27 14:07:18 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 27 14:07:18 rivo systemd[1]: Stopped RAAT DAEMON. Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo volumio[3287]: info: Executing endpoint restartRAATSocket Feb 27 14:07:18 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 27 14:07:18 rivo volumio[3287]: info: Starting Shairport Sync Feb 27 14:07:18 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:18 rivo systemd[1]: vtcs.service: Succeeded. Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:18 rivo sudo[5074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 14:07:18 rivo sudo[5074]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:07:19 rivo sudo[5006]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo volumio[3287]: info: Not Reporting Auto name since its the default one Feb 27 14:07:19 rivo sudo[5011]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo sudo[5040]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo sudo[5035]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo volumio[3287]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 27 14:07:19 rivo volumio[3287]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 27 14:07:19 rivo sudo[5067]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo systemd[1]: Started RAAT DAEMON. Feb 27 14:07:19 rivo sudo[5064]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:19 rivo sudo[5089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 27 14:07:19 rivo sudo[5089]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo sudo[4982]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo sudo[5020]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:19 rivo sudo[5074]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:19 rivo volumio[3287]: info: Shairport-Sync Started Feb 27 14:07:19 rivo volumio[3287]: info: Shairport-Sync Started Feb 27 14:07:19 rivo sudo[5099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 27 14:07:19 rivo sudo[5099]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo volumio[3287]: info: Raat Daemon started successfully Feb 27 14:07:19 rivo volumio[3287]: info: Shairport-Sync Started Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 27 14:07:19 rivo systemd[1]: Stopping RAAT DAEMON... Feb 27 14:07:19 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:19 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 27 14:07:19 rivo systemd[1]: Stopped RAAT DAEMON. Feb 27 14:07:19 rivo systemd[1]: Started RAAT DAEMON. Feb 27 14:07:19 rivo sudo[5089]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo volumio[3287]: No protocol specified Feb 27 14:07:19 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:19 rivo systemd[1]: Started Volumio Tidal Connect Service. Feb 27 14:07:19 rivo sudo[5099]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo volumio[3287]: info: Asound.conf file written Feb 27 14:07:19 rivo sudo[5114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 27 14:07:19 rivo sudo[5114]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo sudo[5114]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo sudo[5117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 27 14:07:19 rivo sudo[5117]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo sudo[5117]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo sudo[5118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 27 14:07:19 rivo sudo[5118]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo systemd[1]: Stopping RAAT DAEMON... Feb 27 14:07:19 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:19 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 27 14:07:19 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Feb 27 14:07:19 rivo kernel: Err: can not set rate to 4262967296! Feb 27 14:07:19 rivo kernel: Range[3920000 - 500000000] Feb 27 14:07:19 rivo kernel: out of value, fixed it Feb 27 14:07:19 rivo kernel: id=0 set inskew=0 Feb 27 14:07:19 rivo systemd[1]: Stopped RAAT DAEMON. Feb 27 14:07:19 rivo volumio[3287]: No protocol specified Feb 27 14:07:19 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:19 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Feb 27 14:07:19 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Feb 27 14:07:19 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Feb 27 14:07:19 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Feb 27 14:07:19 rivo volumio[3287]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Feb 27 14:07:19 rivo volumio[3287]: info: Output device has changed, restarting MPD Feb 27 14:07:19 rivo systemd[1]: Started RAAT DAEMON. Feb 27 14:07:19 rivo sudo[5118]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo volumio[3287]: info: Output device has changed, restarting Shairport Sync Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:19 rivo sudo[5124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 14:07:19 rivo sudo[5124]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo sudo[5124]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo sudo[5131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 14:07:19 rivo sudo[5131]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo volumio[3287]: No protocol specified Feb 27 14:07:19 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:19 rivo volumio[3287]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 14:07:19 rivo volumio[3287]: No protocol specified Feb 27 14:07:19 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:19 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:19 rivo volumio[3287]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:19 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:19 rivo sudo[5140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:19 rivo sudo[5140]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo volumio[3287]: info: Preparing to generate the ALSA configuration file Feb 27 14:07:19 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Feb 27 14:07:19 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:19 rivo systemd[1]: vtcs.service: Succeeded. Feb 27 14:07:19 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Feb 27 14:07:19 rivo sudo[5140]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:19 rivo sudo[5148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:19 rivo sudo[5148]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:19 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:19 rivo volumio[3287]: info: Raat Daemon started successfully Feb 27 14:07:19 rivo volumio[3287]: info: TidalConnect service stoped! Feb 27 14:07:19 rivo volumio[3287]: info: RAAT: Requesting Headphone Status Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 27 14:07:19 rivo volumio[3287]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 27 14:07:19 rivo volumio[3287]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 14:07:19 rivo volumio[3287]: info: Reading ALSA contributions from plugins. Feb 27 14:07:19 rivo sudo[5148]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 27 14:07:19 rivo volumio[3287]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 27 14:07:19 rivo volumio[3287]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 27 14:07:19 rivo volumio[3287]: info: MPD Permissions set Feb 27 14:07:19 rivo volumio[3287]: info: Raat Daemon started successfully Feb 27 14:07:19 rivo volumio[3287]: info: TidalConnect service stoped! Feb 27 14:07:19 rivo volumio[3287]: info: Executing endpoint tc_getconfig Feb 27 14:07:19 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: TidalConnect service stoped! Feb 27 14:07:20 rivo volumio[3287]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 27 14:07:20 rivo volumio[3287]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 27 14:07:20 rivo volumio[3287]: info: TidalConnect service stoped! Feb 27 14:07:20 rivo sudo[5167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 27 14:07:20 rivo sudo[5167]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:20 rivo volumio[3287]: info: Starting Shairport Sync Feb 27 14:07:20 rivo systemd[1]: Started Volumio Tidal Connect Service. Feb 27 14:07:20 rivo sudo[5167]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:20 rivo sudo[5175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 14:07:20 rivo sudo[5175]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:20 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 14:07:20 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 27 14:07:20 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: Not Reporting Auto name since its the default one Feb 27 14:07:20 rivo volumio[3287]: info: RAAT: Requesting Headphone Status Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 27 14:07:20 rivo volumio[3287]: info: Asound.conf file unchanged, so no further update is needed Feb 27 14:07:20 rivo volumio[3287]: info: Output device has changed, restarting MPD Feb 27 14:07:20 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:20 rivo sudo[5175]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:20 rivo sudo[5190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 14:07:20 rivo sudo[5190]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:20 rivo sudo[5190]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:20 rivo volumio[3287]: info: Output device has changed, restarting Shairport Sync Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:20 rivo sudo[5193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 14:07:20 rivo sudo[5193]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:20 rivo volumio[3287]: No protocol specified Feb 27 14:07:20 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:20 rivo volumio[3287]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 14:07:20 rivo volumio[3287]: No protocol specified Feb 27 14:07:20 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:20 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo sudo[5203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:20 rivo sudo[5203]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:20 rivo volumio[3287]: info: Preparing to generate the ALSA configuration file Feb 27 14:07:20 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Feb 27 14:07:20 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:20 rivo sudo[5209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:20 rivo sudo[5209]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:20 rivo volumio[3287]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 27 14:07:20 rivo volumio[3287]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 27 14:07:20 rivo volumio[3287]: info: MRS: Getting audio outputs on start Feb 27 14:07:20 rivo volumio[3287]: info: MRS: Requesting all other devices output Feb 27 14:07:20 rivo volumio[3287]: info: RAAT: Requesting Headphone Status Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 27 14:07:20 rivo volumio[3287]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 27 14:07:20 rivo volumio[3287]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 14:07:20 rivo volumio[3287]: info: Reading ALSA contributions from plugins. Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:20 rivo volumio[3287]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 27 14:07:20 rivo sudo[5212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 27 14:07:20 rivo sudo[5212]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:20 rivo volumio[3287]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 27 14:07:20 rivo volumio[3287]: info: MPD Permissions set Feb 27 14:07:20 rivo volumio[3287]: info: Shairport-Sync Started Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 27 14:07:20 rivo sudo[5209]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:20 rivo sudo[5203]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:20 rivo systemd[1]: vtcs.service: Succeeded. Feb 27 14:07:20 rivo systemd[1]: Started Volumio Tidal Connect Service. Feb 27 14:07:20 rivo sudo[5212]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:20 rivo volumio[3287]: No protocol specified Feb 27 14:07:20 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo sudo[5225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 27 14:07:20 rivo sudo[5225]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 27 14:07:20 rivo sudo[5225]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:20 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:20 rivo sudo[5232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 27 14:07:20 rivo sudo[5232]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:20 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:21 rivo volumio[3287]: (node:3287) UnhandledPromiseRejectionWarning: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Feb 27 14:07:21 rivo volumio[3287]: Job for vtcs.service canceled. Feb 27 14:07:21 rivo volumio[3287]: at ChildProcess.exithandler (child_process.js:383:12) Feb 27 14:07:21 rivo volumio[3287]: at ChildProcess.emit (events.js:400:28) Feb 27 14:07:21 rivo volumio[3287]: at maybeClose (internal/child_process.js:1088:16) Feb 27 14:07:21 rivo volumio[3287]: at Socket. (internal/child_process.js:446:11) Feb 27 14:07:21 rivo volumio[3287]: at Socket.emit (events.js:400:28) Feb 27 14:07:21 rivo volumio[3287]: at Pipe. (net.js:686:12) Feb 27 14:07:21 rivo volumio[3287]: (node:3287) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Feb 27 14:07:21 rivo volumio[3287]: (node:3287) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Feb 27 14:07:21 rivo volumio[3287]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Feb 27 14:07:21 rivo volumio[3287]: Job for vtcs.service canceled. Feb 27 14:07:21 rivo volumio[3287]: {"killed":false,"code":1,"signal":null,"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","stdout":"","stderr":"Job for vtcs.service canceled.\n","stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (child_process.js:383:12)\n at ChildProcess.emit (events.js:400:28)\n at maybeClose (internal/child_process.js:1088:16)\n at Socket. (internal/child_process.js:446:11)\n at Socket.emit (events.js:400:28)\n at Pipe. (net.js:686:12)"} Feb 27 14:07:21 rivo volumio[3287]: info: Executing endpoint restartRAATSocket Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 27 14:07:21 rivo systemd[1]: Stopping RAAT DAEMON... Feb 27 14:07:21 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:21 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 27 14:07:21 rivo systemd[1]: Stopped RAAT DAEMON. Feb 27 14:07:21 rivo volumio[3287]: info: Starting Shairport Sync Feb 27 14:07:21 rivo systemd[1]: Started RAAT DAEMON. Feb 27 14:07:21 rivo sudo[5232]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:21 rivo sudo[5237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 14:07:21 rivo sudo[5237]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:21 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 14:07:21 rivo volumio[3287]: info: Not Reporting Auto name since its the default one Feb 27 14:07:21 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 27 14:07:21 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:21 rivo systemd[1]: shairport-sync.service: Start request repeated too quickly. Feb 27 14:07:21 rivo systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Feb 27 14:07:21 rivo systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 14:07:21 rivo sudo[5237]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:21 rivo volumio[3287]: info: Raat Daemon started successfully Feb 27 14:07:21 rivo volumio[3287]: info: Asound.conf file unchanged, so no further update is needed Feb 27 14:07:21 rivo volumio[3287]: info: Output device has changed, restarting MPD Feb 27 14:07:21 rivo sudo[5248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 14:07:21 rivo sudo[5248]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:21 rivo volumio[3287]: info: Output device has changed, restarting Shairport Sync Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:21 rivo sudo[5248]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 14:07:21 rivo sudo[5251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 14:07:21 rivo sudo[5251]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:21 rivo volumio[3287]: No protocol specified Feb 27 14:07:21 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:21 rivo volumio[3287]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 14:07:21 rivo volumio[3287]: No protocol specified Feb 27 14:07:21 rivo volumio[3287]: xcb_connection_has_error() returned true Feb 27 14:07:21 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:21 rivo volumio[3287]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:21 rivo volumio[3287]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 14:07:21 rivo sudo[5259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:21 rivo sudo[5259]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:21 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Feb 27 14:07:21 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 27 14:07:21 rivo sudo[5264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 27 14:07:21 rivo sudo[5264]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:21 rivo volumio[3287]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 27 14:07:21 rivo volumio[3287]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Feb 27 14:07:21 rivo volumio[3287]: Job for shairport-sync.service failed. Feb 27 14:07:21 rivo volumio[3287]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:21 rivo volumio[3287]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 14:07:21 rivo volumio[3287]: info: Enabling MyMusic plugin upnp Feb 27 14:07:21 rivo volumio[3287]: info: Enabling plugin upnp Feb 27 14:07:21 rivo volumio[3287]: info: Loading plugin "upnp"... Feb 27 14:07:21 rivo volumio[3287]: info: [1740661641688] Starting Upmpd Daemon Feb 27 14:07:21 rivo volumio[3287]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 27 14:07:21 rivo volumio[3287]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 14:07:21 rivo volumio[3287]: Error: listen EADDRINUSE: address already in use :::6599 Feb 27 14:07:21 rivo volumio[3287]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Feb 27 14:07:21 rivo volumio[3287]: at listenInCluster (net.js:1379:12) Feb 27 14:07:21 rivo volumio[3287]: at Server.listen (net.js:1465:7) Feb 27 14:07:21 rivo volumio[3287]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 27 14:07:21 rivo volumio[3287]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 27 14:07:21 rivo volumio[3287]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 27 14:07:21 rivo volumio[3287]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 27 14:07:21 rivo volumio[3287]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Feb 27 14:07:21 rivo volumio[3287]: code: 'EADDRINUSE', Feb 27 14:07:21 rivo volumio[3287]: errno: -98, Feb 27 14:07:21 rivo volumio[3287]: syscall: 'listen', Feb 27 14:07:21 rivo volumio[3287]: address: '::', Feb 27 14:07:21 rivo volumio[3287]: port: 6599 Feb 27 14:07:21 rivo volumio[3287]: } Feb 27 14:07:21 rivo volumio[3287]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 14:07:22 rivo systemd[1]: vtcs.service: Succeeded. Feb 27 14:07:22 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Feb 27 14:07:22 rivo sudo[5277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-27 14:06 Feb 27 14:07:22 rivo sudo[5277]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 14:07:22 rivo sudo[5259]: pam_unix(sudo:session): session closed for user root Feb 27 14:07:22 rivo sudo[5264]: pam_unix(sudo:session): session closed for user root 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"