-- Logs begin at Mon 2024-12-23 00:23:42 CET, end at Mon 2025-03-03 11:14:29 CET. -- Mar 03 11:13:00 rivo ntpd[3279]: 144.91.126.59 local addr 192.168.0.55 -> Mar 03 11:13:01 rivo volumio[3292]: STREAMING PROXY: Client dropped request, destroying Mar 03 11:13:05 rivo volumio[3292]: Searching plugin music_service/qobuz Mar 03 11:13:05 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 03 11:13:06 rivo volumio[3292]: info: searchQOBUZUri took 578 milliseconds Mar 03 11:13:06 rivo volumio[3292]: info: All search sources collected, pushing search results Mar 03 11:13:08 rivo volumio[3292]: Searching plugin music_service/qobuz Mar 03 11:13:08 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 03 11:13:09 rivo volumio[3292]: info: searchQOBUZUri took 893 milliseconds Mar 03 11:13:09 rivo volumio[3292]: info: All search sources collected, pushing search results Mar 03 11:13:14 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 11:13:14 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 11:13:14 rivo volumio[3292]: info: Discovery: Getting this device information Mar 03 11:13:14 rivo volumio[3292]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:14 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 11:13:23 rivo kernel: CFG80211-ERROR) wl_tdls_event_handler : TDLS PEER DISCONNECTED : 9e:81:aa:f1:09:4b on primary ndev Mar 03 11:13:23 rivo kernel: [35B blob data] Mar 03 11:13:23 rivo kernel: CFG80211-ERROR) wl_notify_rx_mgmt_frame : TDLS Action Frame Received type = 3 Mar 03 11:13:30 rivo volumio[3292]: Searching plugin music_service/qobuz Mar 03 11:13:30 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 03 11:13:30 rivo volumio[3292]: Searching plugin music_service/qobuz Mar 03 11:13:30 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 03 11:13:31 rivo volumio[3292]: info: searchQOBUZUri took 1001 milliseconds Mar 03 11:13:31 rivo volumio[3292]: info: All search sources collected, pushing search results Mar 03 11:13:32 rivo volumio[3292]: info: searchQOBUZUri took 1231 milliseconds Mar 03 11:13:32 rivo volumio[3292]: info: All search sources collected, pushing search results Mar 03 11:13:32 rivo volumio[3292]: Searching plugin music_service/qobuz Mar 03 11:13:32 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 03 11:13:35 rivo volumio[3292]: Searching plugin music_service/qobuz Mar 03 11:13:35 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 03 11:13:39 rivo volumio[3292]: Searching plugin music_service/qobuz Mar 03 11:13:39 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 03 11:13:40 rivo volumio[3292]: info: searchQOBUZUri took 819 milliseconds Mar 03 11:13:40 rivo volumio[3292]: info: All search sources collected, pushing search results Mar 03 11:13:41 rivo volumio[3292]: Searching plugin music_service/qobuz Mar 03 11:13:41 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 03 11:13:41 rivo volumio[3292]: info: searchQOBUZUri took 706 milliseconds Mar 03 11:13:41 rivo volumio[3292]: info: All search sources collected, pushing search results Mar 03 11:13:45 rivo volumio[3292]: info: Preload queue cleared Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::ClearQueue Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::stop Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::stPlaybackTimer Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::updateTrackBlock Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrackBlock Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::pushState Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 4 Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioPushState Mar 03 11:13:45 rivo volumio[3292]: info: MRS: Pushing multiroomSync output update for this device Mar 03 11:13:45 rivo volumio[3292]: info: MRS: Pushing multiroomSync output Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::serviceStop Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 4 Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::serviceStop Mar 03 11:13:45 rivo volumio[3292]: info: [1740996825461] ControllerQobuz::stop Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 03 11:13:45 rivo volumio[3292]: info: ControllerMpd::stop Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::clearPlayQueue Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::saveQueue Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioPushQueue Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::addQueueItems Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::addQueueItems Mar 03 11:13:45 rivo volumio[3292]: info: Preload queue cleared Mar 03 11:13:45 rivo volumio[3292]: info: Adding Item to queue: qobuz://song/224530440 Mar 03 11:13:45 rivo volumio[3292]: info: Using cached record of: qobuz://song/224530440 Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioPushQueue Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::saveQueue Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::updateTrackBlock Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrackBlock Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioPlay Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::play index 0 Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::stop Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::play index undefined Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::startPlaybackTimer Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 03 11:13:45 rivo volumio[3292]: info: [1740996825491] ControllerQobuz::clearAddPlayTrack Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 11:13:45 rivo volumio[3292]: info: Updating RAAT Signal Path Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 11:13:45 rivo volumio[3292]: info: Discovery: Getting this device information Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 11:13:45 rivo volumio[3292]: info: Mar 03 11:13:45 rivo volumio[3292]: ---------------------------- MPD announces state update: player Mar 03 11:13:45 rivo volumio[3292]: info: sendMpdCommand stop took 75 milliseconds Mar 03 11:13:45 rivo volumio[3292]: info: ControllerMpd::getState Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand status Mar 03 11:13:45 rivo volumio[3292]: info: sendMpdCommand stop took 46 milliseconds Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand clear Mar 03 11:13:45 rivo volumio[3292]: info: Mar 03 11:13:45 rivo volumio[3292]: ---------------------------- MPD announces state update: player Mar 03 11:13:45 rivo volumio[3292]: info: ControllerMpd::getState Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand status Mar 03 11:13:45 rivo volumio[3292]: info: Mar 03 11:13:45 rivo volumio[3292]: ---------------------------- MPD announces state update: player Mar 03 11:13:45 rivo volumio[3292]: info: ControllerMpd::getState Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand status Mar 03 11:13:45 rivo volumio[3292]: info: Mar 03 11:13:45 rivo volumio[3292]: ---------------------------- MPD announces state update: player Mar 03 11:13:45 rivo volumio[3292]: info: ControllerMpd::getState Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand status Mar 03 11:13:45 rivo volumio[3292]: info: Mar 03 11:13:45 rivo volumio[3292]: ---------------------------- MPD announces system playlist update Mar 03 11:13:45 rivo volumio[3292]: info: Ignoring MPD Status Update Mar 03 11:13:45 rivo volumio[3292]: info: sendMpdCommand status took 11 milliseconds Mar 03 11:13:45 rivo volumio[3292]: info: sendMpdCommand clear took 10 milliseconds Mar 03 11:13:45 rivo volumio[3292]: info: sendMpdCommand status took 8 milliseconds Mar 03 11:13:45 rivo volumio[3292]: info: sendMpdCommand status took 7 milliseconds Mar 03 11:13:45 rivo volumio[3292]: info: sendMpdCommand status took 5 milliseconds Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::parseState Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/224530440" Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::parseState Mar 03 11:13:45 rivo volumio[3292]: info: ControllerMpd::pushState Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::servicePushState Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::pushState Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioPushState Mar 03 11:13:45 rivo volumio[3292]: STREAMING PROXY: Handling url /?data=qobuz://song/224530440 Mar 03 11:13:45 rivo volumio[3292]: info: MRS: Pushing multiroomSync output update for this device Mar 03 11:13:45 rivo volumio[3292]: info: MRS: Pushing multiroomSync output Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:45 rivo volumio[3292]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Mar 03 11:13:45 rivo volumio[3292]: info: ControllerMpd::pushState Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::servicePushState Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::pushState Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioPushState Mar 03 11:13:45 rivo volumio[3292]: info: MRS: Pushing multiroomSync output update for this device Mar 03 11:13:45 rivo volumio[3292]: info: MRS: Pushing multiroomSync output Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:45 rivo volumio[3292]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Mar 03 11:13:45 rivo volumio[3292]: info: ControllerMpd::pushState Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::servicePushState Mar 03 11:13:45 rivo volumio[3292]: info: CoreStateMachine::pushState Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::volumioPushState Mar 03 11:13:45 rivo volumio[3292]: info: MRS: Pushing multiroomSync output update for this device Mar 03 11:13:45 rivo volumio[3292]: info: MRS: Pushing multiroomSync output Mar 03 11:13:45 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:45 rivo volumio[3292]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Mar 03 11:13:45 rivo volumio[3292]: info: ------------------------------ 51ms Mar 03 11:13:45 rivo volumio[3292]: info: ------------------------------ 51ms Mar 03 11:13:45 rivo volumio[3292]: info: ------------------------------ 50ms Mar 03 11:13:45 rivo volumio[3292]: info: Mar 03 11:13:45 rivo volumio[3292]: ---------------------------- MPD announces system playlist update Mar 03 11:13:45 rivo volumio[3292]: info: Ignoring MPD Status Update Mar 03 11:13:45 rivo volumio[3292]: info: Mar 03 11:13:45 rivo volumio[3292]: ---------------------------- MPD announces system playlist update Mar 03 11:13:45 rivo volumio[3292]: info: Ignoring MPD Status Update Mar 03 11:13:45 rivo volumio[3292]: info: Mar 03 11:13:45 rivo volumio[3292]: ---------------------------- MPD announces system playlist update Mar 03 11:13:45 rivo volumio[3292]: info: Ignoring MPD Status Update Mar 03 11:13:45 rivo volumio[3292]: error: updateQueue error: null Mar 03 11:13:45 rivo volumio[3292]: info: ------------------------------ 71ms Mar 03 11:13:45 rivo volumio[3292]: info: sendMpdCommand playlistinfo took 67 milliseconds Mar 03 11:13:45 rivo volumio[3292]: verbose: ControllerMpd::parseTrackInfo Mar 03 11:13:45 rivo volumio[3292]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 03 11:13:45 rivo volumio[3292]: info: ------------------------------ 87ms Mar 03 11:13:45 rivo volumio[3292]: info: Updating RAAT Signal Path Mar 03 11:13:45 rivo volumio[3292]: info: Updating RAAT Signal Path Mar 03 11:13:45 rivo volumio[3292]: info: Updating RAAT Signal Path Mar 03 11:13:45 rivo volumio[3292]: info: Executing endpoint getStreamUrlqobuz Mar 03 11:13:45 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 03 11:13:47 rivo volumio[3292]: info: Executing endpoint metavolumio Mar 03 11:13:47 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 11:13:47 rivo volumio[3292]: info: Executing endpoint metavolumio Mar 03 11:13:47 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 11:13:47 rivo volumio[3292]: info: Executing endpoint metavolumio Mar 03 11:13:47 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 11:13:50 rivo volumio[3292]: info: CoreCommandRouter::volumioPlay Mar 03 11:13:50 rivo volumio[3292]: info: CoreStateMachine::play index undefined Mar 03 11:13:50 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:13:50 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:50 rivo volumio[3292]: info: CoreStateMachine::startPlaybackTimer Mar 03 11:13:50 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:50 rivo volumio[3292]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 11:13:50 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:50 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 03 11:13:50 rivo volumio[3292]: info: [1740996830866] ControllerQobuz::clearAddPlayTrack Mar 03 11:13:50 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 11:13:53 rivo systemd[1]: Stopping Music Player Daemon... Mar 03 11:13:53 rivo volumio[3292]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 11:13:54 rivo volumio[3292]: error: Failed request for metavolumio API Mar 03 11:14:08 rivo volumio[3292]: info: CoreCommandRouter::volumioPlay Mar 03 11:14:08 rivo volumio[3292]: info: CoreStateMachine::play index undefined Mar 03 11:14:08 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:08 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:08 rivo volumio[3292]: info: CoreStateMachine::startPlaybackTimer Mar 03 11:14:08 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:08 rivo volumio[3292]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 11:14:08 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:14:08 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 03 11:14:08 rivo volumio[3292]: info: [1740996848030] ControllerQobuz::clearAddPlayTrack Mar 03 11:14:08 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 11:14:12 rivo volumio[3292]: info: Preload queue cleared Mar 03 11:14:12 rivo volumio[3292]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 03 11:14:12 rivo volumio[3292]: info: CoreStateMachine::ClearQueue Mar 03 11:14:12 rivo volumio[3292]: info: CoreStateMachine::stop Mar 03 11:14:12 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:12 rivo volumio[3292]: info: CorePlayQueue::clearPlayQueue Mar 03 11:14:12 rivo volumio[3292]: info: CorePlayQueue::saveQueue Mar 03 11:14:12 rivo volumio[3292]: info: CoreCommandRouter::volumioPushQueue Mar 03 11:14:12 rivo volumio[3292]: info: CoreStateMachine::addQueueItems Mar 03 11:14:12 rivo volumio[3292]: info: CorePlayQueue::addQueueItems Mar 03 11:14:12 rivo volumio[3292]: info: Preload queue cleared Mar 03 11:14:12 rivo volumio[3292]: info: Adding Item to queue: qobuz://song/224530440 Mar 03 11:14:12 rivo volumio[3292]: info: Using cached record of: qobuz://song/224530440 Mar 03 11:14:13 rivo volumio[3292]: info: CoreCommandRouter::volumioPushQueue Mar 03 11:14:13 rivo volumio[3292]: info: CorePlayQueue::saveQueue Mar 03 11:14:13 rivo volumio[3292]: info: CoreStateMachine::updateTrackBlock Mar 03 11:14:13 rivo volumio[3292]: info: CorePlayQueue::getTrackBlock Mar 03 11:14:13 rivo volumio[3292]: info: CoreCommandRouter::volumioPlay Mar 03 11:14:13 rivo volumio[3292]: info: CoreStateMachine::play index 0 Mar 03 11:14:13 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:13 rivo volumio[3292]: info: CoreStateMachine::stop Mar 03 11:14:13 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:13 rivo volumio[3292]: info: CoreStateMachine::play index undefined Mar 03 11:14:13 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:13 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:13 rivo volumio[3292]: info: CoreStateMachine::startPlaybackTimer Mar 03 11:14:13 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:13 rivo volumio[3292]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 11:14:13 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:14:13 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 03 11:14:13 rivo volumio[3292]: info: [1740996853022] ControllerQobuz::clearAddPlayTrack Mar 03 11:14:13 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 11:14:14 rivo volumio[3292]: info: Preload queue cleared Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::ClearQueue Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::stop Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:14 rivo volumio[3292]: info: CorePlayQueue::clearPlayQueue Mar 03 11:14:14 rivo volumio[3292]: info: CorePlayQueue::saveQueue Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::volumioPushQueue Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::addQueueItems Mar 03 11:14:14 rivo volumio[3292]: info: CorePlayQueue::addQueueItems Mar 03 11:14:14 rivo volumio[3292]: info: Preload queue cleared Mar 03 11:14:14 rivo volumio[3292]: info: Adding Item to queue: qobuz://song/224530440 Mar 03 11:14:14 rivo volumio[3292]: info: Using cached record of: qobuz://song/224530440 Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::volumioPushQueue Mar 03 11:14:14 rivo volumio[3292]: info: CorePlayQueue::saveQueue Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::updateTrackBlock Mar 03 11:14:14 rivo volumio[3292]: info: CorePlayQueue::getTrackBlock Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::volumioPlay Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::play index 0 Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::stop Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::play index undefined Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:14 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:14 rivo volumio[3292]: info: CoreStateMachine::startPlaybackTimer Mar 03 11:14:14 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 03 11:14:14 rivo volumio[3292]: info: [1740996854378] ControllerQobuz::clearAddPlayTrack Mar 03 11:14:14 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 11:14:14 rivo volumio[3292]: info: Discovery: Getting this device information Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::volumioGetState Mar 03 11:14:14 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:14 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 11:14:17 rivo volumio[3292]: info: Preload queue cleared Mar 03 11:14:17 rivo volumio[3292]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::ClearQueue Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::stop Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:17 rivo volumio[3292]: info: CorePlayQueue::clearPlayQueue Mar 03 11:14:17 rivo volumio[3292]: info: CorePlayQueue::saveQueue Mar 03 11:14:17 rivo volumio[3292]: info: CoreCommandRouter::volumioPushQueue Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::addQueueItems Mar 03 11:14:17 rivo volumio[3292]: info: CorePlayQueue::addQueueItems Mar 03 11:14:17 rivo volumio[3292]: info: Preload queue cleared Mar 03 11:14:17 rivo volumio[3292]: info: Adding Item to queue: qobuz://song/224530440 Mar 03 11:14:17 rivo volumio[3292]: info: Using cached record of: qobuz://song/224530440 Mar 03 11:14:17 rivo volumio[3292]: info: CoreCommandRouter::volumioPushQueue Mar 03 11:14:17 rivo volumio[3292]: info: CorePlayQueue::saveQueue Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::updateTrackBlock Mar 03 11:14:17 rivo volumio[3292]: info: CorePlayQueue::getTrackBlock Mar 03 11:14:17 rivo volumio[3292]: info: CoreCommandRouter::volumioPlay Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::play index 0 Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::stop Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::play index undefined Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 11:14:17 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:17 rivo volumio[3292]: info: CoreStateMachine::startPlaybackTimer Mar 03 11:14:17 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:17 rivo volumio[3292]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 11:14:17 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:14:17 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 03 11:14:17 rivo volumio[3292]: info: [1740996857545] ControllerQobuz::clearAddPlayTrack Mar 03 11:14:17 rivo volumio[3292]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 11:14:22 rivo volumio[3292]: info: CoreCommandRouter::volumioGetState Mar 03 11:14:22 rivo volumio[3292]: info: CorePlayQueue::getTrack 0 Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 11:14:24 rivo volumio[3292]: info: Retrieving Cloud Streaming UI Mar 03 11:14:24 rivo volumio[3292]: info: Getting Tidal Cloud Configuration Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 11:14:24 rivo volumio[3292]: info: Getting Qobuz Cloud Configuration Mar 03 11:14:24 rivo volumio[3292]: info: Asking plugin for UI Config Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 11:14:24 rivo volumio[3292]: info: Getting Spotify Cloud Configuration Mar 03 11:14:24 rivo volumio[3292]: info: Asking plugin for UI Config Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 11:14:24 rivo volumio[3292]: info: Saving Spotify Acccount Mar 03 11:14:24 rivo volumio[3292]: info: Got it Mar 03 11:14:24 rivo volumio[3292]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 03 11:14:24 rivo volumio[3292]: info: Got Tidal Cloud Configuration Mar 03 11:14:24 rivo volumio[3292]: info: Got it Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 11:14:24 rivo volumio[3292]: info: Executing endpoint metavolumio Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 11:14:24 rivo volumio[3292]: info: Executing endpoint metavolumio Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 11:14:24 rivo volumio[3292]: info: Executing endpoint metavolumio Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 11:14:24 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 03 11:14:27 rivo volumio[3292]: info: Disabling MyMusic plugin upnp Mar 03 11:14:27 rivo sudo[10470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 03 11:14:27 rivo sudo[10470]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:14:27 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 03 11:14:27 rivo volumio[3292]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 11:14:28 rivo volumio[3292]: info: Enabling MyMusic plugin upnp Mar 03 11:14:28 rivo volumio[3292]: info: Enabling plugin upnp Mar 03 11:14:28 rivo volumio[3292]: info: Loading plugin "upnp"... Mar 03 11:14:28 rivo volumio[3292]: info: [1740996868244] Starting Upmpd Daemon Mar 03 11:14:28 rivo volumio[3292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 11:14:28 rivo volumio[3292]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 11:14:28 rivo volumio[3292]: Error: listen EADDRINUSE: address already in use :::6599 Mar 03 11:14:28 rivo volumio[3292]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Mar 03 11:14:28 rivo volumio[3292]: at listenInCluster (net.js:1379:12) Mar 03 11:14:28 rivo volumio[3292]: at Server.listen (net.js:1465:7) Mar 03 11:14:28 rivo volumio[3292]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 03 11:14:28 rivo volumio[3292]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 03 11:14:28 rivo volumio[3292]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 03 11:14:28 rivo volumio[3292]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 03 11:14:28 rivo volumio[3292]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Mar 03 11:14:28 rivo volumio[3292]: code: 'EADDRINUSE', Mar 03 11:14:28 rivo volumio[3292]: errno: -98, Mar 03 11:14:28 rivo volumio[3292]: syscall: 'listen', Mar 03 11:14:28 rivo volumio[3292]: address: '::', Mar 03 11:14:28 rivo volumio[3292]: port: 6599 Mar 03 11:14:28 rivo volumio[3292]: } Mar 03 11:14:28 rivo volumio[3292]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 11:14:29 rivo sudo[10481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 11:13 Mar 03 11:14:29 rivo sudo[10481]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"