-- Logs begin at Mon 2024-12-23 00:23:42 CET, end at Sat 2025-03-01 15:58:45 CET. -- Mar 01 15:57:13 rivo volumio[3313]: 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: 7 Mar 01 15:57:13 rivo volumio[3313]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.149 UA: Mozilla/5.0 (Linux; Android 12; SM-T865 Build/SP2A.220305.013; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.121 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 01 15:57:13 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::volumioGetVisibleSources Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:57:14 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:57:14 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:57:14 rivo volumio[3313]: info: CoreStateMachine::getQueue Mar 01 15:57:14 rivo volumio[3313]: info: CorePlayQueue::getQueue Mar 01 15:57:14 rivo volumio[3313]: info: Listing playlists Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 01 15:57:14 rivo volumio[3313]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 01 15:57:14 rivo volumio[3313]: info: Received Get System Info Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:57:14 rivo volumio[3313]: info: Discovery: Getting this device information Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:57:14 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:57:14 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:57:14 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 01 15:57:16 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 01 15:57:16 rivo volumio[3313]: info: Received Get System Info Mar 01 15:57:16 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:57:16 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:57:16 rivo volumio[3313]: info: Discovery: Getting this device information Mar 01 15:57:16 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:57:16 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:57:16 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:57:17 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:57:17 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:57:17 rivo volumio[3313]: info: Discovery: Getting this device information Mar 01 15:57:17 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:57:17 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:57:17 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:57:31 rivo volumio[3313]: Searching plugin music_service/qobuz Mar 01 15:57:31 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 01 15:57:32 rivo volumio[3313]: info: searchQOBUZUri took 1157 milliseconds Mar 01 15:57:32 rivo volumio[3313]: info: All search sources collected, pushing search results Mar 01 15:57:33 rivo volumio[3313]: Searching plugin music_service/qobuz Mar 01 15:57:33 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 01 15:57:34 rivo volumio[3313]: Searching plugin music_service/qobuz Mar 01 15:57:34 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 01 15:57:34 rivo volumio[3313]: info: searchQOBUZUri took 1245 milliseconds Mar 01 15:57:34 rivo volumio[3313]: info: All search sources collected, pushing search results Mar 01 15:57:35 rivo volumio[3313]: info: searchQOBUZUri took 1567 milliseconds Mar 01 15:57:35 rivo volumio[3313]: info: All search sources collected, pushing search results Mar 01 15:57:38 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 01 15:57:40 rivo volumio[3313]: info: handleBrowseUri took 2368 milliseconds Mar 01 15:57:40 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:57:41 rivo volumio[3313]: info: Executing endpoint getSimilarArtists Mar 01 15:57:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Mar 01 15:57:41 rivo volumio[3313]: info: Executing endpoint getSimilarArtists Mar 01 15:57:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Mar 01 15:57:41 rivo volumio[3313]: info: Executing endpoint metavolumio Mar 01 15:57:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 01 15:57:41 rivo volumio[3313]: info: Executing endpoint metavolumio Mar 01 15:57:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 01 15:57:54 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 01 15:57:55 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:57:55 rivo volumio[3313]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:57:55 rivo volumio[3313]: info: CoreStateMachine::ClearQueue Mar 01 15:57:55 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:57:55 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:57:55 rivo volumio[3313]: info: CorePlayQueue::clearPlayQueue Mar 01 15:57:55 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:57:55 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:57:55 rivo volumio[3313]: info: CoreStateMachine::addQueueItems Mar 01 15:57:55 rivo volumio[3313]: info: CorePlayQueue::addQueueItems Mar 01 15:57:55 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:57:55 rivo volumio[3313]: info: Adding Item to queue: qobuz://album/0060253749183 Mar 01 15:57:55 rivo volumio[3313]: info: Exploding uri qobuz://album/0060253749183 in service qobuz Mar 01 15:57:55 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:57:57 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:57:57 rivo volumio[3313]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:57:57 rivo volumio[3313]: info: CoreStateMachine::ClearQueue Mar 01 15:57:57 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:57:57 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:57:57 rivo volumio[3313]: info: CorePlayQueue::clearPlayQueue Mar 01 15:57:57 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:57:57 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:57:57 rivo volumio[3313]: info: CoreStateMachine::addQueueItems Mar 01 15:57:57 rivo volumio[3313]: info: CorePlayQueue::addQueueItems Mar 01 15:57:57 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:57:57 rivo volumio[3313]: info: Adding Item to queue: qobuz://album/0060253749183 Mar 01 15:57:57 rivo volumio[3313]: info: Exploding uri qobuz://album/0060253749183 in service qobuz Mar 01 15:57:57 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:57:57 rivo volumio[3313]: error: error Mar 01 15:58:00 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:00 rivo volumio[3313]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:58:00 rivo volumio[3313]: info: CoreStateMachine::ClearQueue Mar 01 15:58:00 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:00 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:00 rivo volumio[3313]: info: CorePlayQueue::clearPlayQueue Mar 01 15:58:00 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:00 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:00 rivo volumio[3313]: info: CoreStateMachine::addQueueItems Mar 01 15:58:00 rivo volumio[3313]: info: CorePlayQueue::addQueueItems Mar 01 15:58:00 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:00 rivo volumio[3313]: info: Adding Item to queue: qobuz://album/0060253749183 Mar 01 15:58:00 rivo volumio[3313]: info: Exploding uri qobuz://album/0060253749183 in service qobuz Mar 01 15:58:00 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: handleBrowseUri took 8431 milliseconds Mar 01 15:58:03 rivo volumio[3313]: info: explodeUri took 2472 milliseconds Mar 01 15:58:03 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:03 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:03 rivo volumio[3313]: info: CoreStateMachine::updateTrackBlock Mar 01 15:58:03 rivo volumio[3313]: info: CorePlayQueue::getTrackBlock Mar 01 15:58:03 rivo volumio[3313]: info: CoreCommandRouter::volumioPlay Mar 01 15:58:03 rivo volumio[3313]: info: CoreStateMachine::play index 0 Mar 01 15:58:03 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:03 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:03 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:03 rivo volumio[3313]: info: CoreStateMachine::play index undefined Mar 01 15:58:03 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:03 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:03 rivo volumio[3313]: info: CoreStateMachine::startPlaybackTimer Mar 01 15:58:03 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:03 rivo volumio[3313]: info: [1740841083203] ControllerQobuz::clearAddPlayTrack Mar 01 15:58:03 rivo volumio[3313]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:58:03 rivo volumio[3313]: info: sendMpdCommand stop took 25 milliseconds Mar 01 15:58:03 rivo volumio[3313]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 15:58:03 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244006 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244007 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244008 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244009 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244010 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244011 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244012 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244013 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244014 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244015 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244017 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244019 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244021 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244022 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244023 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244024 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244025 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244026 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244027 Mar 01 15:58:03 rivo volumio[3313]: info: Preloading song: qobuz://song/11244028 Mar 01 15:58:03 rivo volumio[3313]: info: Mar 01 15:58:03 rivo volumio[3313]: ---------------------------- MPD announces system playlist update Mar 01 15:58:03 rivo volumio[3313]: info: Ignoring MPD Status Update Mar 01 15:58:03 rivo volumio[3313]: info: sendMpdCommand clear took 16 milliseconds Mar 01 15:58:03 rivo volumio[3313]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/11244006" Mar 01 15:58:03 rivo volumio[3313]: info: Mar 01 15:58:03 rivo volumio[3313]: ---------------------------- MPD announces system playlist update Mar 01 15:58:03 rivo volumio[3313]: info: Ignoring MPD Status Update Mar 01 15:58:03 rivo volumio[3313]: info: Mar 01 15:58:03 rivo volumio[3313]: ---------------------------- MPD announces system playlist update Mar 01 15:58:03 rivo volumio[3313]: info: Ignoring MPD Status Update Mar 01 15:58:03 rivo volumio[3313]: error: updateQueue error: null Mar 01 15:58:03 rivo volumio[3313]: info: ------------------------------ 14ms Mar 01 15:58:03 rivo volumio[3313]: error: Upnp client error: Error: This socket has been ended by the other party Mar 01 15:58:03 rivo volumio[3313]: STREAMING PROXY: Handling url /?data=qobuz://song/11244006 Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244006 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Executing endpoint getStreamUrlqobuz Mar 01 15:58:03 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244007 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244008 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244009 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244010 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244011 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Executing endpoint getSimilarAlbums Mar 01 15:58:03 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 01 15:58:03 rivo volumio[3313]: info: Executing endpoint getSimilarAlbums Mar 01 15:58:03 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244012 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Executing endpoint metavolumio Mar 01 15:58:03 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 01 15:58:03 rivo volumio[3313]: info: Executing endpoint metavolumio Mar 01 15:58:03 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 01 15:58:03 rivo volumio[3313]: info: Executing endpoint metavolumio Mar 01 15:58:03 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244013 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244014 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244015 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244017 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244019 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Executing endpoint metavolumio Mar 01 15:58:03 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244021 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244022 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:03 rivo volumio[3313]: info: Exploding uri qobuz://song/11244023 in service qobuz Mar 01 15:58:03 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:04 rivo volumio[3313]: info: Exploding uri qobuz://song/11244024 in service qobuz Mar 01 15:58:04 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:04 rivo volumio[3313]: info: Exploding uri qobuz://song/11244025 in service qobuz Mar 01 15:58:04 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:04 rivo volumio[3313]: info: Exploding uri qobuz://song/11244026 in service qobuz Mar 01 15:58:04 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:04 rivo volumio[3313]: info: Exploding uri qobuz://song/11244027 in service qobuz Mar 01 15:58:04 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:04 rivo volumio[3313]: info: Exploding uri qobuz://song/11244028 in service qobuz Mar 01 15:58:04 rivo volumio[3313]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 01 15:58:07 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:07 rivo volumio[3313]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::ClearQueue Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:07 rivo volumio[3313]: info: CorePlayQueue::clearPlayQueue Mar 01 15:58:07 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:07 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::addQueueItems Mar 01 15:58:07 rivo volumio[3313]: info: CorePlayQueue::addQueueItems Mar 01 15:58:07 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:07 rivo volumio[3313]: info: Adding Item to queue: qobuz://album/0060253749183 Mar 01 15:58:07 rivo volumio[3313]: info: Using cached record of: qobuz://album/0060253749183 Mar 01 15:58:07 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:07 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::updateTrackBlock Mar 01 15:58:07 rivo volumio[3313]: info: CorePlayQueue::getTrackBlock Mar 01 15:58:07 rivo volumio[3313]: info: CoreCommandRouter::volumioPlay Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::play index 0 Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::play index undefined Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:07 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:07 rivo volumio[3313]: info: CoreStateMachine::startPlaybackTimer Mar 01 15:58:07 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:07 rivo volumio[3313]: info: [1740841087602] ControllerQobuz::clearAddPlayTrack Mar 01 15:58:07 rivo volumio[3313]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:58:09 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:09 rivo volumio[3313]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::ClearQueue Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:09 rivo volumio[3313]: info: CorePlayQueue::clearPlayQueue Mar 01 15:58:09 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:09 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::addQueueItems Mar 01 15:58:09 rivo volumio[3313]: info: CorePlayQueue::addQueueItems Mar 01 15:58:09 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:09 rivo volumio[3313]: info: Adding Item to queue: qobuz://album/0060253749183 Mar 01 15:58:09 rivo volumio[3313]: info: Using cached record of: qobuz://album/0060253749183 Mar 01 15:58:09 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:09 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::updateTrackBlock Mar 01 15:58:09 rivo volumio[3313]: info: CorePlayQueue::getTrackBlock Mar 01 15:58:09 rivo volumio[3313]: info: CoreCommandRouter::volumioPlay Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::play index 0 Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::play index undefined Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:09 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:09 rivo volumio[3313]: info: CoreStateMachine::startPlaybackTimer Mar 01 15:58:09 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:09 rivo volumio[3313]: info: [1740841089318] ControllerQobuz::clearAddPlayTrack Mar 01 15:58:09 rivo volumio[3313]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:58:09 rivo volumio[3313]: info: explodeUri took 6035 milliseconds Mar 01 15:58:10 rivo volumio[3313]: error: Failed request for metavolumio API Mar 01 15:58:10 rivo volumio[3313]: error: Failed request for metavolumio API Mar 01 15:58:10 rivo volumio[3313]: error: Failed request for metavolumio API Mar 01 15:58:10 rivo volumio[3313]: error: Failed request for metavolumio API Mar 01 15:58:12 rivo systemd[1]: Stopping Music Player Daemon... Mar 01 15:58:12 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:13 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:13 rivo volumio[3313]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::ClearQueue Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:13 rivo volumio[3313]: info: CorePlayQueue::clearPlayQueue Mar 01 15:58:13 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:13 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::addQueueItems Mar 01 15:58:13 rivo volumio[3313]: info: CorePlayQueue::addQueueItems Mar 01 15:58:13 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:13 rivo volumio[3313]: info: Adding Item to queue: qobuz://album/0060253749183 Mar 01 15:58:13 rivo volumio[3313]: info: Using cached record of: qobuz://album/0060253749183 Mar 01 15:58:13 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:13 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::updateTrackBlock Mar 01 15:58:13 rivo volumio[3313]: info: CorePlayQueue::getTrackBlock Mar 01 15:58:13 rivo volumio[3313]: info: CoreCommandRouter::volumioPlay Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::play index 0 Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::play index undefined Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:13 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:13 rivo volumio[3313]: info: CoreStateMachine::startPlaybackTimer Mar 01 15:58:13 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:13 rivo volumio[3313]: info: [1740841093102] ControllerQobuz::clearAddPlayTrack Mar 01 15:58:13 rivo volumio[3313]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:58:13 rivo volumio[3313]: info: explodeUri took 10536 milliseconds Mar 01 15:58:13 rivo volumio[3313]: info: explodeUri took 10523 milliseconds Mar 01 15:58:13 rivo volumio[3313]: info: explodeUri took 10438 milliseconds Mar 01 15:58:13 rivo volumio[3313]: info: explodeUri took 10502 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10313 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10467 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10277 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10012 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10450 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 9974 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10222 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10203 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10323 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10192 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 9955 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10032 milliseconds Mar 01 15:58:14 rivo volumio[3313]: info: explodeUri took 10127 milliseconds Mar 01 15:58:15 rivo volumio[3313]: info: explodeUri took 11334 milliseconds Mar 01 15:58:15 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:15 rivo volumio[3313]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::ClearQueue Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:15 rivo volumio[3313]: info: CorePlayQueue::clearPlayQueue Mar 01 15:58:15 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:15 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::addQueueItems Mar 01 15:58:15 rivo volumio[3313]: info: CorePlayQueue::addQueueItems Mar 01 15:58:15 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:15 rivo volumio[3313]: info: Adding Item to queue: qobuz://album/0060253749183 Mar 01 15:58:15 rivo volumio[3313]: info: Using cached record of: qobuz://album/0060253749183 Mar 01 15:58:15 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:15 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::updateTrackBlock Mar 01 15:58:15 rivo volumio[3313]: info: CorePlayQueue::getTrackBlock Mar 01 15:58:15 rivo volumio[3313]: info: CoreCommandRouter::volumioPlay Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::play index 0 Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::play index undefined Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:15 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:15 rivo volumio[3313]: info: CoreStateMachine::startPlaybackTimer Mar 01 15:58:15 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:15 rivo volumio[3313]: info: [1740841095427] ControllerQobuz::clearAddPlayTrack Mar 01 15:58:15 rivo volumio[3313]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:58:19 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:19 rivo volumio[3313]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::ClearQueue Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:19 rivo volumio[3313]: info: CorePlayQueue::clearPlayQueue Mar 01 15:58:19 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:19 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::addQueueItems Mar 01 15:58:19 rivo volumio[3313]: info: CorePlayQueue::addQueueItems Mar 01 15:58:19 rivo volumio[3313]: info: Preload queue cleared Mar 01 15:58:19 rivo volumio[3313]: info: Adding Item to queue: qobuz://album/0060253749183 Mar 01 15:58:19 rivo volumio[3313]: info: Using cached record of: qobuz://album/0060253749183 Mar 01 15:58:19 rivo volumio[3313]: info: CoreCommandRouter::volumioPushQueue Mar 01 15:58:19 rivo volumio[3313]: info: CorePlayQueue::saveQueue Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::updateTrackBlock Mar 01 15:58:19 rivo volumio[3313]: info: CorePlayQueue::getTrackBlock Mar 01 15:58:19 rivo volumio[3313]: info: CoreCommandRouter::volumioPlay Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::play index 0 Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::stop Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::play index undefined Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 15:58:19 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:19 rivo volumio[3313]: info: CoreStateMachine::startPlaybackTimer Mar 01 15:58:19 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:19 rivo volumio[3313]: info: [1740841099465] ControllerQobuz::clearAddPlayTrack Mar 01 15:58:19 rivo volumio[3313]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 15:58:19 rivo kernel: [35B blob data] Mar 01 15:58:19 rivo kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 2 Mar 01 15:58:19 rivo kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER CONNECTED : 9e:81:aa:f1:09:4b on primary ndev Mar 01 15:58:20 rivo volumio[3313]: info: getStreamUrl took 17554 milliseconds Mar 01 15:58:20 rivo volumio[3313]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3077903&eid=11244006&fmt=6&profile=raw&app_id=539451548&cid=2610646&etsp=1740844700&hmac=uDMO3uAKmpmqZfyQRN61url2LTY Mar 01 15:58:21 rivo volumio[3313]: STREAMING PROXY: Response: 200, length: 19278633 Mar 01 15:58:21 rivo volumio[3313]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/11244006" Mar 01 15:58:21 rivo volumio[3313]: error: MPD error: Error: read ECONNRESET Mar 01 15:58:21 rivo volumio[3313]: Upnp client error: Error: This socket has been ended by the other party Mar 01 15:58:21 rivo volumio[3313]: STREAMING PROXY: Client dropped request, destroying Mar 01 15:58:21 rivo systemd[1]: mpd.service: Succeeded. Mar 01 15:58:21 rivo systemd[1]: Stopped Music Player Daemon. Mar 01 15:58:21 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:21 rivo systemd[1]: Starting Music Player Daemon... Mar 01 15:58:21 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:21 rivo sudo[4582]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 15:58:21 rivo sudo[4582]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:21 rivo sudo[4582]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:23 rivo mpd[4591]: Mar 01 15:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 01 15:58:23 rivo systemd[1]: Started Music Player Daemon. Mar 01 15:58:23 rivo mpd_monitor.sh[2856]: MPD restarted due to no mpc output. Mar 01 15:58:26 rivo volumio[3313]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.149 UA: Mozilla/5.0 (Linux; Android 12; SM-T865 Build/SP2A.220305.013; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.121 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 01 15:58:26 rivo volumio[3313]: error: updateQueue error: null Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:58:26 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:26 rivo volumio[3313]: info: Received Get System Info Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:58:26 rivo volumio[3313]: info: Discovery: Getting this device information Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:58:26 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:58:26 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::volumioGetVisibleSources Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:58:26 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:58:26 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::volumioGetQueue Mar 01 15:58:26 rivo volumio[3313]: info: CoreStateMachine::getQueue Mar 01 15:58:26 rivo volumio[3313]: info: CorePlayQueue::getQueue Mar 01 15:58:26 rivo volumio[3313]: info: Listing playlists Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 01 15:58:26 rivo volumio[3313]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 01 15:58:26 rivo volumio[3313]: info: Received Get System Info Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 01 15:58:26 rivo volumio[3313]: info: Discovery: Getting this device information Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:58:26 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:58:26 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:26 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 01 15:58:30 rivo volumio[3313]: info: CoreCommandRouter::volumioGetState Mar 01 15:58:30 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:32 rivo volumio[3313]: info: Executing endpoint metavolumio Mar 01 15:58:32 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 01 15:58:32 rivo volumio[3313]: info: Executing endpoint metavolumio Mar 01 15:58:32 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 01 15:58:32 rivo volumio[3313]: info: Executing endpoint metavolumio Mar 01 15:58:32 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 15:58:34 rivo volumio[3313]: info: Retrieving Cloud Streaming UI Mar 01 15:58:34 rivo volumio[3313]: info: Getting Tidal Cloud Configuration Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 15:58:34 rivo volumio[3313]: info: Getting Qobuz Cloud Configuration Mar 01 15:58:34 rivo volumio[3313]: info: Asking plugin for UI Config Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 15:58:34 rivo volumio[3313]: info: Getting Spotify Cloud Configuration Mar 01 15:58:34 rivo volumio[3313]: info: Asking plugin for UI Config Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 15:58:34 rivo volumio[3313]: info: Saving Spotify Acccount Mar 01 15:58:34 rivo volumio[3313]: info: Got it Mar 01 15:58:34 rivo volumio[3313]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 01 15:58:34 rivo volumio[3313]: info: Got Tidal Cloud Configuration Mar 01 15:58:34 rivo volumio[3313]: info: Got it Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::volumioGetBrowseSources Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::volumioGetBrowseSources Mar 01 15:58:34 rivo volumio[3313]: info: CoreCommandRouter::volumioGetBrowseSources Mar 01 15:58:35 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 01 15:58:35 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 01 15:58:36 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 01 15:58:36 rivo volumio[3313]: info: Disabling MyMusic plugin bluetooth Mar 01 15:58:36 rivo sudo[4675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Mar 01 15:58:36 rivo sudo[4675]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:36 rivo systemd[1]: Stopping Volumio Bluetooth Module... Mar 01 15:58:36 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:36 rivo volumiobt[4677]: Killing pulse Mar 01 15:58:36 rivo volumiobt[4677]: Killing BT Pair agent Mar 01 15:58:36 rivo bluetoothd[3207]: Endpoint unregistered: sender=:1.43 path=/MediaEndpoint/A2DPSource Mar 01 15:58:36 rivo bluetoothd[3207]: Endpoint unregistered: sender=:1.43 path=/MediaEndpoint/A2DPSink Mar 01 15:58:36 rivo systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:36 rivo systemd[1]: volumiobt.service: Succeeded. Mar 01 15:58:36 rivo systemd[1]: Stopped Volumio Bluetooth Module. Mar 01 15:58:36 rivo sudo[4675]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:36 rivo volumio[3313]: info: Volumio BT Module successfully stopped Mar 01 15:58:36 rivo volumio[3313]: info: Disabling plugin bluetooth Mar 01 15:58:36 rivo volumio[3313]: info: Done. Mar 01 15:58:37 rivo volumio[3313]: info: Enabling MyMusic plugin bluetooth Mar 01 15:58:37 rivo volumio[3313]: info: Enabling plugin bluetooth Mar 01 15:58:37 rivo volumio[3313]: info: Loading plugin "bluetooth"... Mar 01 15:58:37 rivo volumio[3313]: info: [1740841117595] Starting BluetoothController Mar 01 15:58:37 rivo volumio[3313]: info: PLUGIN START: bluetooth Mar 01 15:58:37 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 01 15:58:37 rivo volumio[3313]: info: Done. Mar 01 15:58:37 rivo sudo[4681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Mar 01 15:58:37 rivo sudo[4681]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:37 rivo sudo[4681]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:37 rivo volumio[3313]: info: Bluetooth name changed to Rivo Mar 01 15:58:39 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 01 15:58:39 rivo volumio[3313]: info: Disabling MyMusic plugin multiroom Mar 01 15:58:39 rivo volumio[3313]: info: Disabling plugin multiroom Mar 01 15:58:39 rivo volumio[3313]: info: Preparing to generate the ALSA configuration file Mar 01 15:58:39 rivo volumio[3313]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 01 15:58:39 rivo volumio[3313]: info: Reading ALSA contributions from plugins. Mar 01 15:58:39 rivo volumio[3313]: info: Asound.conf file written Mar 01 15:58:39 rivo sudo[4684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 01 15:58:39 rivo sudo[4684]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:39 rivo sudo[4684]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:39 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Mar 01 15:58:39 rivo kernel: Err: can not set rate to 4278967296! Mar 01 15:58:39 rivo kernel: Range[3920000 - 500000000] Mar 01 15:58:39 rivo kernel: out of value, fixed it Mar 01 15:58:39 rivo kernel: id=0 set inskew=0 Mar 01 15:58:39 rivo volumio[3313]: No protocol specified Mar 01 15:58:39 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:39 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Mar 01 15:58:39 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Mar 01 15:58:39 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Mar 01 15:58:39 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Mar 01 15:58:39 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Mar 01 15:58:39 rivo volumio[3313]: info: Output device has changed, restarting MPD Mar 01 15:58:40 rivo sudo[4689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 15:58:40 rivo sudo[4689]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo volumio[3313]: info: Output device has changed, restarting Shairport Sync Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 15:58:40 rivo sudo[4689]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo sudo[4692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 15:58:40 rivo sudo[4692]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo systemd[1]: Stopping Music Player Daemon... Mar 01 15:58:40 rivo systemd[1]: mpd.service: Succeeded. Mar 01 15:58:40 rivo volumio[3313]: No protocol specified Mar 01 15:58:40 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:40 rivo volumio[3313]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 15:58:40 rivo volumio[3313]: No protocol specified Mar 01 15:58:40 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:40 rivo systemd[1]: Stopped Music Player Daemon. Mar 01 15:58:40 rivo volumio[3313]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo systemd[1]: Starting Music Player Daemon... Mar 01 15:58:40 rivo volumio[3313]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo vtcs[4083]: [2025-03-01 15:58:40.146] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 01 15:58:40 rivo vtcs[4083]: [2025-03-01 15:58:40.148] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 01 15:58:40 rivo volumio[3313]: info: Done. Mar 01 15:58:40 rivo sudo[4710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 15:58:40 rivo sudo[4710]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:40 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:40 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:40 rivo volumio[3313]: info: MPD Permissions set Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Mar 01 15:58:40 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo systemd[1]: vtcs.service: Succeeded. Mar 01 15:58:40 rivo sudo[4713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 15:58:40 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo sudo[4713]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo sudo[4710]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo sudo[4698]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 15:58:40 rivo sudo[4698]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo sudo[4713]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo sudo[4698]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:40 rivo volumio[3313]: info: Starting Shairport Sync Mar 01 15:58:40 rivo sudo[4726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 15:58:40 rivo sudo[4726]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 15:58:40 rivo systemd[1]: shairport-sync.service: Succeeded. Mar 01 15:58:40 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 15:58:40 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:40 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:40 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 15:58:40 rivo sudo[4726]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo volumio[3313]: info: Shairport-Sync Started Mar 01 15:58:40 rivo volumio[3313]: info: Enabling MyMusic plugin multiroom Mar 01 15:58:40 rivo volumio[3313]: info: Enabling plugin multiroom Mar 01 15:58:40 rivo volumio[3313]: info: Loading plugin "multiroom"... Mar 01 15:58:40 rivo volumio[3313]: info: Applying required configuration parameters for plugin multiroom Mar 01 15:58:40 rivo sudo[4732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 01 15:58:40 rivo sudo[4732]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo sudo[4732]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo volumio[3313]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Mar 01 15:58:40 rivo volumio[3313]: info: MRS: MultiRoom plugin initialized Mar 01 15:58:40 rivo volumio[3313]: info: MRS: STOPPING SNAPCLIENT Mar 01 15:58:40 rivo volumio[3313]: info: MRS: Snap server stop Mar 01 15:58:40 rivo sudo[4749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 01 15:58:40 rivo sudo[4749]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo volumio[3313]: info: MRS: STOPPING volumioStreaming Mar 01 15:58:40 rivo sudo[4752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 01 15:58:40 rivo sudo[4752]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo sudo[4755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 01 15:58:40 rivo sudo[4749]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo sudo[4755]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo volumio[3313]: info: Preparing to generate the ALSA configuration file Mar 01 15:58:40 rivo sudo[4752]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo sudo[4758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 01 15:58:40 rivo sudo[4758]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo sudo[4755]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo sudo[4758]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:40 rivo sudo[4762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 01 15:58:40 rivo sudo[4762]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 15:58:40 rivo volumio[3313]: info: Not Reporting Auto name since its the default one Mar 01 15:58:40 rivo systemd[1]: Started Volumio Bluetooth Module. Mar 01 15:58:41 rivo sudo[4762]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:41 rivo volumio[3313]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 01 15:58:41 rivo volumio[3313]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 01 15:58:41 rivo volumio[3313]: info: Reading ALSA contributions from plugins. Mar 01 15:58:41 rivo volumio[3313]: info: MRS: Removed streaming files Mar 01 15:58:41 rivo volumio[3313]: info: MRS: volumioStreaming STOPPED Mar 01 15:58:41 rivo volumio[3313]: info: MRS: SNAPSERVER STOPPED Mar 01 15:58:41 rivo volumio[3313]: info: MRS: SNAPCLIENT STOPPED Mar 01 15:58:41 rivo volumio[3313]: info: Volumio BT Module successfully started Mar 01 15:58:41 rivo sudo[4769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 01 15:58:41 rivo sudo[4769]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 01 15:58:41 rivo volumiobt[4768]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 01 15:58:41 rivo sudo[4769]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:41 rivo volumio[3313]: No protocol specified Mar 01 15:58:41 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:41 rivo volumio[3313]: info: Asound.conf file written Mar 01 15:58:41 rivo sudo[4775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 01 15:58:41 rivo sudo[4775]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:41 rivo sudo[4775]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:41 rivo sudo[4778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 01 15:58:41 rivo sudo[4778]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:41 rivo sudo[4779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 01 15:58:41 rivo sudo[4779]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:41 rivo sudo[4778]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:41 rivo systemd[1]: Stopping RAAT DAEMON... Mar 01 15:58:41 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:41 rivo systemd[1]: raat-daemon.service: Succeeded. Mar 01 15:58:41 rivo systemd[1]: Stopped RAAT DAEMON. Mar 01 15:58:41 rivo systemd[1]: Started RAAT DAEMON. Mar 01 15:58:41 rivo sudo[4779]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:41 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Mar 01 15:58:41 rivo kernel: Err: can not set rate to 4274967296! Mar 01 15:58:41 rivo kernel: Range[3920000 - 500000000] Mar 01 15:58:41 rivo kernel: out of value, fixed it Mar 01 15:58:41 rivo kernel: id=0 set inskew=0 Mar 01 15:58:41 rivo volumio[3313]: No protocol specified Mar 01 15:58:41 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:41 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Mar 01 15:58:41 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Mar 01 15:58:41 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Mar 01 15:58:41 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Mar 01 15:58:41 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Mar 01 15:58:41 rivo volumio[3313]: info: Output device has changed, restarting MPD Mar 01 15:58:41 rivo sudo[4791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 15:58:41 rivo volumio[3313]: info: Output device has changed, restarting Shairport Sync Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 15:58:41 rivo sudo[4791]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:41 rivo sudo[4791]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:41 rivo sudo[4794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 15:58:41 rivo sudo[4794]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:41 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:41 rivo systemd[1]: mpd.service: Succeeded. Mar 01 15:58:41 rivo systemd[1]: Stopped Music Player Daemon. Mar 01 15:58:41 rivo systemd[1]: Starting Music Player Daemon... Mar 01 15:58:41 rivo volumio[3313]: No protocol specified Mar 01 15:58:41 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:41 rivo volumio[3313]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 15:58:41 rivo volumio[3313]: No protocol specified Mar 01 15:58:41 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:41 rivo volumio[3313]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo sudo[4804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 15:58:41 rivo sudo[4804]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:41 rivo volumio[3313]: info: PLUGIN START: multiroom Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 01 15:58:41 rivo volumio[3313]: info: MRS: Pushing multiroomSync output for this device Mar 01 15:58:41 rivo volumio[3313]: info: MRS: Pushing multiroomSync output Mar 01 15:58:41 rivo volumio[3313]: info: Adding audio output: Mar 01 15:58:41 rivo volumio[3313]: error: Can't add: Mar 01 15:58:41 rivo volumio[3313]: info: Adding audio output: Mar 01 15:58:41 rivo volumio[3313]: error: Can't add: Mar 01 15:58:41 rivo volumio[3313]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo sudo[4804]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:41 rivo sudo[4800]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 15:58:41 rivo sudo[4800]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:41 rivo sudo[4800]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:41 rivo sudo[4817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 15:58:41 rivo sudo[4817]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:41 rivo sudo[4817]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:41 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:41 rivo volumio[3313]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 01 15:58:41 rivo volumio[3313]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 01 15:58:41 rivo volumio[3313]: info: MPD Permissions set Mar 01 15:58:41 rivo volumio[3313]: info: Raat Daemon started successfully Mar 01 15:58:41 rivo volumio[3313]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:41 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: Not Reporting Auto name since its the default one Mar 01 15:58:42 rivo volumio[3313]: info: Starting Shairport Sync Mar 01 15:58:42 rivo sudo[4830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 01 15:58:42 rivo sudo[4830]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:42 rivo volumio[3313]: info: Preparing to generate the ALSA configuration file Mar 01 15:58:42 rivo volumiobt[4768]: pulseaudio: no process found Mar 01 15:58:42 rivo sudo[4830]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 01 15:58:42 rivo sudo[4833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 15:58:42 rivo sudo[4833]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:42 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 15:58:42 rivo sudo[4833]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:42 rivo systemd[1]: shairport-sync.service: Succeeded. Mar 01 15:58:42 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 15:58:42 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 15:58:42 rivo volumio[3313]: No protocol specified Mar 01 15:58:42 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:42 rivo volumio[3313]: info: Done. Mar 01 15:58:42 rivo volumio[3313]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 01 15:58:42 rivo volumio[3313]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 01 15:58:42 rivo volumio[3313]: info: Reading ALSA contributions from plugins. Mar 01 15:58:42 rivo sudo[4844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 01 15:58:42 rivo sudo[4844]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:42 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:42 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:42 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:42 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:42 rivo sudo[4844]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:42 rivo volumio[3313]: info: Shairport-Sync Started Mar 01 15:58:42 rivo sudo[4846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 01 15:58:42 rivo sudo[4846]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:42 rivo volumio[3313]: info: Asound.conf file written Mar 01 15:58:42 rivo systemd[1]: Stopping RAAT DAEMON... Mar 01 15:58:42 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:42 rivo systemd[1]: raat-daemon.service: Succeeded. Mar 01 15:58:42 rivo systemd[1]: Stopped RAAT DAEMON. Mar 01 15:58:42 rivo systemd[1]: Started RAAT DAEMON. Mar 01 15:58:42 rivo sudo[4846]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:42 rivo sudo[4849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 01 15:58:42 rivo sudo[4849]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:42 rivo sudo[4849]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:42 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Mar 01 15:58:42 rivo kernel: Err: can not set rate to 4270967296! Mar 01 15:58:42 rivo kernel: Range[3920000 - 500000000] Mar 01 15:58:42 rivo kernel: out of value, fixed it Mar 01 15:58:42 rivo kernel: id=0 set inskew=0 Mar 01 15:58:42 rivo volumio[3313]: No protocol specified Mar 01 15:58:42 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:42 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Mar 01 15:58:42 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Mar 01 15:58:42 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Mar 01 15:58:42 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Mar 01 15:58:42 rivo volumio[3313]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Topping Internal Clock Validity:0' : Operation not permitted Mar 01 15:58:42 rivo volumio[3313]: info: Output device has changed, restarting MPD Mar 01 15:58:42 rivo pulseaudio[4838]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 01 15:58:42 rivo pulseaudio[4838]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 01 15:58:42 rivo volumiobt[4768]: Applying permissions Mar 01 15:58:42 rivo volumiobt[4768]: Setting BT discoverable and pairable Mar 01 15:58:42 rivo pulseaudio[4838]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Mar 01 15:58:42 rivo sudo[4861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 15:58:42 rivo sudo[4861]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:42 rivo volumio[3313]: info: Output device has changed, restarting Shairport Sync Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 15:58:42 rivo sudo[4861]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:42 rivo bluetoothd[3207]: Endpoint registered: sender=:1.70 path=/MediaEndpoint/A2DPSource Mar 01 15:58:42 rivo bluetoothd[3207]: Endpoint registered: sender=:1.70 path=/MediaEndpoint/A2DPSink Mar 01 15:58:42 rivo volumiobt[4768]: [CHG] Controller 70:4A:0E:AA:6C:47 Class: 0x0008041c Mar 01 15:58:42 rivo volumiobt[4768]: [117B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [117B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [117B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [117B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [117B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [117B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [117B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [117B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [117B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [55B blob data] Mar 01 15:58:42 rivo volumiobt[4768]: [bluetooth]# -e power on Mar 01 15:58:42 rivo volumiobt[4768]: Invalid command in menu main: -e Mar 01 15:58:42 rivo volumiobt[4768]: Mar 01 15:58:42 rivo volumiobt[4768]: Use "help" for a list of available commands in a menu. Mar 01 15:58:42 rivo volumiobt[4768]: Use "menu " if you want to enter any submenu. Mar 01 15:58:42 rivo volumiobt[4768]: Use "back" if you want to return to menu main. Mar 01 15:58:42 rivo volumiobt[4768]: [bluetooth]# agent on Mar 01 15:58:42 rivo volumiobt[4768]: Agent is already registered Mar 01 15:58:42 rivo volumiobt[4768]: [bluetooth]# discoverable on Mar 01 15:58:42 rivo volumiobt[4768]: [bluetooth]# pairable on Mar 01 15:58:42 rivo volumiobt[4768]: [bluetooth]# agent NoInputNoOutput Mar 01 15:58:42 rivo volumiobt[4768]: Agent is already registered Mar 01 15:58:42 rivo volumiobt[4768]: [bluetooth]# default-agent Mar 01 15:58:42 rivo volumiobt[4768]: [bluetooth]# quit Mar 01 15:58:42 rivo sudo[4866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 15:58:42 rivo sudo[4866]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:42 rivo volumiobt[4768]: [59B blob data] Mar 01 15:58:42 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:42 rivo systemd[1]: mpd.service: Succeeded. Mar 01 15:58:42 rivo systemd[1]: Stopped Music Player Daemon. Mar 01 15:58:42 rivo volumio[3313]: No protocol specified Mar 01 15:58:42 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:42 rivo volumio[3313]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 15:58:42 rivo volumio[3313]: No protocol specified Mar 01 15:58:42 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:42 rivo volumio[3313]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:42 rivo systemd[1]: Starting Music Player Daemon... Mar 01 15:58:42 rivo volumio[3313]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 01 15:58:42 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: Preparing to generate the ALSA configuration file Mar 01 15:58:43 rivo sudo[4877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 15:58:43 rivo sudo[4877]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:43 rivo sudo[4873]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 15:58:43 rivo sudo[4873]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:43 rivo sudo[4877]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:43 rivo sudo[4873]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:43 rivo volumio[3313]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 01 15:58:43 rivo volumio[3313]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 01 15:58:43 rivo volumio[3313]: info: Reading ALSA contributions from plugins. Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:43 rivo sudo[4887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 15:58:43 rivo sudo[4887]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:43 rivo sudo[4887]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 01 15:58:43 rivo volumio[3313]: error: MPD error: Error: This socket has been ended by the other party Mar 01 15:58:43 rivo volumio[3313]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 01 15:58:43 rivo volumio[3313]: error: MPD error: Error: This socket has been ended by the other party Mar 01 15:58:43 rivo volumio[3313]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 01 15:58:43 rivo volumio[3313]: error: MPD error: Error: This socket has been ended by the other party Mar 01 15:58:43 rivo volumio[3313]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 01 15:58:43 rivo volumio[3313]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 01 15:58:43 rivo volumio[3313]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 01 15:58:43 rivo volumio[3313]: info: MPD Permissions set Mar 01 15:58:43 rivo volumio[3313]: info: Raat Daemon started successfully Mar 01 15:58:43 rivo volumio[3313]: info: TidalConnect service stoped! Mar 01 15:58:43 rivo volumio[3313]: info: TidalConnect service stoped! Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: Not Reporting Auto name since its the default one Mar 01 15:58:43 rivo volumio[3313]: info: Disabling MyMusic plugin upnp Mar 01 15:58:43 rivo volumio[3313]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 01 15:58:43 rivo volumio[3313]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 01 15:58:43 rivo sudo[4912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 01 15:58:43 rivo sudo[4912]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:43 rivo volumio[3313]: info: Starting Shairport Sync Mar 01 15:58:43 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:43 rivo sudo[4915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 01 15:58:43 rivo sudo[4915]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:43 rivo volumio[3313]: error: Upnp client error: Error: This socket has been ended by the other party Mar 01 15:58:43 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:43 rivo volumio[3313]: info: Asound.conf file unchanged, so no further update is needed Mar 01 15:58:43 rivo volumio[3313]: info: Output device has changed, restarting MPD Mar 01 15:58:43 rivo sudo[4917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 15:58:43 rivo sudo[4917]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:43 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 15:58:43 rivo systemd[1]: Started Volumio Tidal Connect Service. Mar 01 15:58:43 rivo sudo[4915]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:43 rivo volumio[3313]: info: Output device has changed, restarting Shairport Sync Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 15:58:43 rivo sudo[4921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 15:58:43 rivo sudo[4921]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:43 rivo sudo[4921]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:43 rivo systemd[1]: shairport-sync.service: Succeeded. Mar 01 15:58:43 rivo sudo[4925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 15:58:43 rivo sudo[4925]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:43 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 15:58:43 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 15:58:43 rivo sudo[4917]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:43 rivo volumio[3313]: No protocol specified Mar 01 15:58:43 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:43 rivo volumio[3313]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 15:58:43 rivo volumio[3313]: No protocol specified Mar 01 15:58:43 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:43 rivo volumio[3313]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:43 rivo volumio[3313]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 01 15:58:43 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:43 rivo systemd[1]: mpd.service: Succeeded. Mar 01 15:58:43 rivo systemd[1]: Stopped Music Player Daemon. Mar 01 15:58:43 rivo systemd[1]: Starting Music Player Daemon... Mar 01 15:58:44 rivo volumio[3313]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo sudo[4945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 15:58:44 rivo sudo[4945]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:44 rivo volumio[3313]: info: Preparing to generate the ALSA configuration file Mar 01 15:58:44 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Mar 01 15:58:44 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:44 rivo systemd[1]: vtcs.service: Succeeded. Mar 01 15:58:44 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Mar 01 15:58:44 rivo sudo[4945]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 01 15:58:44 rivo sudo[4951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 01 15:58:44 rivo sudo[4951]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:44 rivo sudo[4942]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 15:58:44 rivo sudo[4942]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:44 rivo sudo[4942]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:44 rivo sudo[4951]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:44 rivo volumio[3313]: No protocol specified Mar 01 15:58:44 rivo volumio[3313]: xcb_connection_has_error() returned true Mar 01 15:58:44 rivo volumio[3313]: info: RAAT: Requesting Headphone Status Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 01 15:58:44 rivo volumio[3313]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 01 15:58:44 rivo volumio[3313]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 01 15:58:44 rivo volumio[3313]: info: Reading ALSA contributions from plugins. Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo sudo[4959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:44 rivo sudo[4959]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:44 rivo sudo[4959]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo volumio[3313]: info: MPD Permissions set Mar 01 15:58:44 rivo volumio[3313]: info: Shairport-Sync Started Mar 01 15:58:44 rivo sudo[4961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 01 15:58:44 rivo sudo[4961]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:44 rivo volumio[3313]: info: Executing endpoint restartRAATSocket Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo systemd[1]: Stopping RAAT DAEMON... Mar 01 15:58:44 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:44 rivo systemd[1]: raat-daemon.service: Succeeded. Mar 01 15:58:44 rivo systemd[1]: Stopped RAAT DAEMON. Mar 01 15:58:44 rivo systemd[1]: Started RAAT DAEMON. Mar 01 15:58:44 rivo sudo[4961]: pam_unix(sudo:session): session closed for user root Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CorePlayQueue::getTrack 0 Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 15:58:44 rivo volumio[3313]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 15:58:44 rivo volumio[3313]: info: Not Reporting Auto name since its the default one Mar 01 15:58:44 rivo volumio[3313]: info: Enabling MyMusic plugin upnp Mar 01 15:58:44 rivo volumio[3313]: info: Enabling plugin upnp Mar 01 15:58:44 rivo volumio[3313]: info: Loading plugin "upnp"... Mar 01 15:58:44 rivo volumio[3313]: info: [1740841124608] Starting Upmpd Daemon Mar 01 15:58:44 rivo volumio[3313]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 01 15:58:44 rivo volumio[3313]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 15:58:44 rivo volumio[3313]: Error: listen EADDRINUSE: address already in use :::6599 Mar 01 15:58:44 rivo volumio[3313]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Mar 01 15:58:44 rivo volumio[3313]: at listenInCluster (net.js:1379:12) Mar 01 15:58:44 rivo volumio[3313]: at Server.listen (net.js:1465:7) Mar 01 15:58:44 rivo volumio[3313]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 01 15:58:44 rivo volumio[3313]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 01 15:58:44 rivo volumio[3313]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 01 15:58:44 rivo volumio[3313]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 01 15:58:44 rivo volumio[3313]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Mar 01 15:58:44 rivo volumio[3313]: code: 'EADDRINUSE', Mar 01 15:58:44 rivo volumio[3313]: errno: -98, Mar 01 15:58:44 rivo volumio[3313]: syscall: 'listen', Mar 01 15:58:44 rivo volumio[3313]: address: '::', Mar 01 15:58:44 rivo volumio[3313]: port: 6599 Mar 01 15:58:44 rivo volumio[3313]: } Mar 01 15:58:44 rivo volumio[3313]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 15:58:45 rivo sudo[4989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 15:57 Mar 01 15:58:45 rivo sudo[4989]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 15:58:45 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 01 15:58:45 rivo systemd[1]: mpd.service: Succeeded. Mar 01 15:58:45 rivo systemd[1]: Stopped Music Player Daemon. Mar 01 15:58:45 rivo systemd[1]: Starting Music Player Daemon... 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"