-- Logs begin at Tue 2024-10-01 18:37:59 CEST, end at Tue 2024-10-01 20:26:50 CEST. -- Oct 01 20:25:00 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:00 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:00 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:00 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:00 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:00 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:00 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:25:00 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:25:00 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:25:01 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume+ Oct 01 20:25:01 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:01 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:01 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:01 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:01 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:01 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:01 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:25:02 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume+ Oct 01 20:25:02 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:02 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:02 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:02 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:02 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:02 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:02 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:25:02 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:25:02 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:25:03 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume+ Oct 01 20:25:03 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:03 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:03 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:03 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:03 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:03 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:03 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:25:04 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume+ Oct 01 20:25:04 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:04 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:04 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:04 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:04 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:04 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:04 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:25:07 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:25:07 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:25:07 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:25:07 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:25:07 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:25:08 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:25:08 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:25:08 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:25:08 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:25:08 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:25:10 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:25:10 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:10 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:10 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:10 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:10 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:10 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:10 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:25:13 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume+ Oct 01 20:25:13 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:13 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:13 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:13 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:13 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:13 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:13 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:25:13 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:25:13 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:25:19 rivoml ntpd[3367]: 129.250.35.250 local addr 192.168.1.22 -> Oct 01 20:25:19 rivoml volumio[3379]: info: CoreCommandRouter::volumioNext Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::next Oct 01 20:25:19 rivoml volumio[3379]: info: [1727807119805] ControllerQobuz::next Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::next Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::stop Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::stPlaybackTimer Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::updateTrackBlock Oct 01 20:25:19 rivoml volumio[3379]: info: CorePlayQueue::getTrackBlock Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:19 rivoml volumio[3379]: info: CorePlayQueue::getTrack 1 Oct 01 20:25:19 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:19 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:19 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:19 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::serviceStop Oct 01 20:25:19 rivoml volumio[3379]: info: CorePlayQueue::getTrack 1 Oct 01 20:25:19 rivoml volumio[3379]: info: CoreCommandRouter::serviceStop Oct 01 20:25:19 rivoml volumio[3379]: info: [1727807119816] ControllerQobuz::stop Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 01 20:25:19 rivoml volumio[3379]: info: ControllerMpd::stop Oct 01 20:25:19 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand stop Oct 01 20:25:19 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:19 rivoml volumio[3379]: info: Oct 01 20:25:19 rivoml volumio[3379]: ---------------------------- MPD announces state update: player Oct 01 20:25:19 rivoml volumio[3379]: info: sendMpdCommand stop took 81 milliseconds Oct 01 20:25:19 rivoml volumio[3379]: info: ControllerMpd::getState Oct 01 20:25:19 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand status Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::play index undefined Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 01 20:25:19 rivoml volumio[3379]: info: CorePlayQueue::getTrack 2 Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::startPlaybackTimer Oct 01 20:25:19 rivoml volumio[3379]: info: CorePlayQueue::getTrack 2 Oct 01 20:25:19 rivoml volumio[3379]: info: [1727807119902] ControllerQobuz::clearAddPlayTrack Oct 01 20:25:19 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand stop Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::updateTrackBlock Oct 01 20:25:19 rivoml volumio[3379]: info: CorePlayQueue::getTrackBlock Oct 01 20:25:19 rivoml volumio[3379]: info: sendMpdCommand status took 7 milliseconds Oct 01 20:25:19 rivoml volumio[3379]: info: sendMpdCommand stop took 5 milliseconds Oct 01 20:25:19 rivoml volumio[3379]: verbose: ControllerMpd::parseState Oct 01 20:25:19 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 20:25:19 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand clear Oct 01 20:25:19 rivoml volumio[3379]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 01 20:25:19 rivoml volumio[3379]: info: sendMpdCommand clear took 2 milliseconds Oct 01 20:25:19 rivoml volumio[3379]: verbose: ControllerMpd::parseTrackInfo Oct 01 20:25:19 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/275894047" Oct 01 20:25:19 rivoml volumio[3379]: info: ControllerMpd::pushState Oct 01 20:25:19 rivoml volumio[3379]: info: CoreCommandRouter::servicePushState Oct 01 20:25:19 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:19 rivoml volumio[3379]: info: CorePlayQueue::getTrack 2 Oct 01 20:25:19 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:19 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:19 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:19 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:19 rivoml volumio[3379]: info: CorePlayQueue::getTrack 2 Oct 01 20:25:19 rivoml volumio[3379]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 01 20:25:19 rivoml volumio[3379]: STREAMING PROXY: Handling url /?data=qobuz://song/275894047 Oct 01 20:25:19 rivoml volumio[3379]: info: ------------------------------ 44ms Oct 01 20:25:19 rivoml volumio[3379]: info: Oct 01 20:25:19 rivoml volumio[3379]: ---------------------------- MPD announces system playlist update Oct 01 20:25:19 rivoml volumio[3379]: info: Ignoring MPD Status Update Oct 01 20:25:19 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:19 rivoml volumio[3379]: info: MCU Signalled Playback Inactive Oct 01 20:25:19 rivoml volumio[3379]: info: Executing endpoint getStreamUrlqobuz Oct 01 20:25:19 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 01 20:25:20 rivoml volumio[3379]: info: getStreamUrl took 535 milliseconds Oct 01 20:25:20 rivoml volumio[3379]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2048063&eid=275894047&fmt=7&profile=raw&app_id=539451548&cid=2322414&etsp=1727810720&hmac=jKO0Lu7kvxHHnhlNrEpl8CoEgKY Oct 01 20:25:20 rivoml volumio[3379]: STREAMING PROXY: Response: 200, length: 74914915 Oct 01 20:25:20 rivoml volumio[3379]: STREAMING PROXY: Client dropped request, destroying Oct 01 20:25:20 rivoml volumio[3379]: error: updateQueue error: null Oct 01 20:25:20 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/275894047" Oct 01 20:25:20 rivoml volumio[3379]: info: ------------------------------ 673ms Oct 01 20:25:20 rivoml volumio[3379]: info: Oct 01 20:25:20 rivoml volumio[3379]: ---------------------------- MPD announces system playlist update Oct 01 20:25:20 rivoml volumio[3379]: info: Ignoring MPD Status Update Oct 01 20:25:20 rivoml volumio[3379]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/275894047" took 7 milliseconds Oct 01 20:25:20 rivoml volumio[3379]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 01 20:25:20 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand play Oct 01 20:25:20 rivoml volumio[3379]: STREAMING PROXY: Handling url /?data=qobuz://song/275894047 Oct 01 20:25:20 rivoml volumio[3379]: info: ------------------------------ 12ms Oct 01 20:25:20 rivoml volumio[3379]: info: sendMpdCommand play took 10 milliseconds Oct 01 20:25:20 rivoml volumio[3379]: info: Executing endpoint getStreamUrlqobuz Oct 01 20:25:20 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 01 20:25:21 rivoml volumio[3379]: info: getStreamUrl took 510 milliseconds Oct 01 20:25:21 rivoml volumio[3379]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2048063&eid=275894047&fmt=7&profile=raw&app_id=539451548&cid=2322414&etsp=1727810721&hmac=3vEfw2VsdvamZp_dPkX4qUiHlaU Oct 01 20:25:21 rivoml volumio[3379]: STREAMING PROXY: Response: 200, length: 74914915 Oct 01 20:25:21 rivoml volumio[3379]: info: Oct 01 20:25:21 rivoml volumio[3379]: ---------------------------- MPD announces state update: player Oct 01 20:25:21 rivoml volumio[3379]: info: ControllerMpd::getState Oct 01 20:25:21 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand status Oct 01 20:25:21 rivoml volumio[3379]: info: Oct 01 20:25:21 rivoml volumio[3379]: ---------------------------- MPD announces state update: player Oct 01 20:25:21 rivoml volumio[3379]: info: sendMpdCommand status took 21 milliseconds Oct 01 20:25:21 rivoml volumio[3379]: info: ControllerMpd::getState Oct 01 20:25:21 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand status Oct 01 20:25:21 rivoml volumio[3379]: verbose: ControllerMpd::parseState Oct 01 20:25:21 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 20:25:21 rivoml volumio[3379]: info: sendMpdCommand status took 4 milliseconds Oct 01 20:25:21 rivoml volumio[3379]: verbose: ControllerMpd::parseState Oct 01 20:25:21 rivoml volumio[3379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 20:25:21 rivoml volumio[3379]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 01 20:25:21 rivoml volumio[3379]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 01 20:25:21 rivoml volumio[3379]: verbose: ControllerMpd::parseTrackInfo Oct 01 20:25:21 rivoml volumio[3379]: info: ControllerMpd::pushState Oct 01 20:25:21 rivoml volumio[3379]: info: CoreCommandRouter::servicePushState Oct 01 20:25:21 rivoml volumio[3379]: info: CorePlayQueue::getTrack 2 Oct 01 20:25:21 rivoml volumio[3379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"275894047","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/275894047","trackType":"qobuz"} Oct 01 20:25:21 rivoml volumio[3379]: verbose: CURRENT POSITION 2 Oct 01 20:25:21 rivoml volumio[3379]: info: CoreStateMachine::syncState stateService play Oct 01 20:25:21 rivoml volumio[3379]: info: CoreStateMachine::syncState currentStatus stop Oct 01 20:25:21 rivoml volumio[3379]: info: ControllerMpd::pushState Oct 01 20:25:21 rivoml volumio[3379]: info: CoreCommandRouter::servicePushState Oct 01 20:25:21 rivoml volumio[3379]: info: CorePlayQueue::getTrack 2 Oct 01 20:25:21 rivoml volumio[3379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"275894047","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/275894047","trackType":"qobuz"} Oct 01 20:25:21 rivoml volumio[3379]: verbose: CURRENT POSITION 2 Oct 01 20:25:21 rivoml volumio[3379]: info: CoreStateMachine::syncState stateService play Oct 01 20:25:21 rivoml volumio[3379]: info: CoreStateMachine::syncState currentStatus play Oct 01 20:25:21 rivoml volumio[3379]: info: Received an update from plugin. extracting info from payload Oct 01 20:25:21 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:21 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:25:21 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:21 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:21 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:21 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:25:21 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:25:21 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:25:21 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:25:21 rivoml volumio[3379]: info: ------------------------------ 87ms Oct 01 20:25:21 rivoml volumio[3379]: info: ------------------------------ 79ms Oct 01 20:25:21 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:21 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:25:21 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:25:21 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:25:21 rivoml volumio[3379]: info: MCU Signalled Playback Active Oct 01 20:25:23 rivoml volumio[3379]: STREAMING PROXY: Client dropped request, destroying Oct 01 20:25:27 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:25:27 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:25:27 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:25:27 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:25:27 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:25:29 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:25:29 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:25:29 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:25:29 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:25:29 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:25:30 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:25:30 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:25:30 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:25:30 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:25:30 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:25:37 rivoml volumio[3379]: No protocol specified Oct 01 20:25:37 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 01 20:25:37 rivoml volumio[3379]: No protocol specified Oct 01 20:25:37 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:25:37 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:25:47 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:25:47 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:25:47 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:25:47 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:25:47 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:25:48 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:25:48 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:25:48 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:25:48 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:25:48 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:25:52 rivoml ntpd[3367]: 82.64.230.205 local addr 192.168.1.22 -> Oct 01 20:25:59 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:25:59 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:25:59 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:25:59 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:25:59 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:26:04 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:04 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:04 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:04 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:04 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:04 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:04 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:04 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:04 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:04 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:06 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:06 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:06 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:06 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:06 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:06 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:06 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:06 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:07 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:26:07 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:26:07 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:26:07 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:26:07 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:26:10 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:10 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:10 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:10 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:10 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:10 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:10 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:10 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:10 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:26:10 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:26:10 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:26:10 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:26:10 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:26:13 rivoml volumio[3379]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 01 20:26:13 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 01 20:26:13 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:26:13 rivoml volumio[3379]: info: Restoring Previous Volume level: 94 false false Oct 01 20:26:13 rivoml volumio[3379]: info: Volume configurations have been set Oct 01 20:26:13 rivoml volumio[3379]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 01 20:26:13 rivoml volumio[3379]: No protocol specified Oct 01 20:26:13 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:13 rivoml volumio[3379]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 01 20:26:13 rivoml volumio[3379]: info: Updating Volume Controller Parameters: Device: 5 Name: GOLD NOTE USB-DAC Mixer: GOLD NOTE USB-DAC Validity Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 01 20:26:13 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 01 20:26:13 rivoml volumio[3379]: info: Disabling external Volume Control Oct 01 20:26:13 rivoml volumio[3379]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:26:13 rivoml volumio[3379]: No protocol specified Oct 01 20:26:13 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:13 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 01 20:26:14 rivoml volumio[3379]: No protocol specified Oct 01 20:26:14 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 01 20:26:14 rivoml volumio[3379]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:14 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: VolumeController:: Volume=undefined Mute =false Oct 01 20:26:14 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:14 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:14 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:14 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:14 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:14 rivoml volumio[3379]: info: Not Reporting Auto name since its the default one Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 01 20:26:14 rivoml volumio[3379]: No protocol specified Oct 01 20:26:14 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:14 rivoml sudo[5359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 01 20:26:14 rivoml sudo[5359]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:26:14 rivoml sudo[5359]: pam_unix(sudo:session): session closed for user root Oct 01 20:26:14 rivoml sudo[5361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 01 20:26:14 rivoml sudo[5361]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:26:14 rivoml systemd[1]: Stopping RAAT DAEMON... Oct 01 20:26:14 rivoml systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 01 20:26:14 rivoml systemd[1]: raat-daemon.service: Succeeded. Oct 01 20:26:14 rivoml systemd[1]: Stopped RAAT DAEMON. Oct 01 20:26:14 rivoml volumio[3379]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 01 20:26:14 rivoml systemd[1]: Started RAAT DAEMON. Oct 01 20:26:14 rivoml sudo[5361]: pam_unix(sudo:session): session closed for user root Oct 01 20:26:14 rivoml volumio[3379]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 01 20:26:14 rivoml volumio[3379]: info: Raat Daemon started successfully Oct 01 20:26:14 rivoml volumio[3379]: info: Executing endpoint restartRAATSocket Oct 01 20:26:14 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 01 20:26:14 rivoml volumio[3379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 01 20:26:18 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume94 Oct 01 20:26:18 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:18 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:18 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:18 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:18 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:18 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:18 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:25 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:25 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:25 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:25 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:25 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:25 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:25 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:25 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:25 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:25 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:25 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:25 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:25 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:25 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:25 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:25 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:25 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:25 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:25 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:25 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:25 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:25 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:25 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:25 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:25 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:25 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:25 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:25 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:25 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:25 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:25 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:25 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:26 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:26 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:26 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:26 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:26 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:26 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:26 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:26 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:26 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:26 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:27 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:27 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:27 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:27 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:27 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:27 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:27 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:27 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:27 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:27 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:27 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:27 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:27 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:27 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:27 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:27 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:27 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:27 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:27 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:28 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:28 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:28 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:28 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:28 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:28 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:28 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:28 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:28 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:28 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:28 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:28 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:28 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:28 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:28 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:28 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:28 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:28 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:28 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:28 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:28 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:28 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:28 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:28 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:28 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:28 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:28 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:28 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:28 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:28 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:28 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:28 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:28 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:28 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:28 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:28 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:28 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:28 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:28 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:28 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:28 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:28 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:28 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:28 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:28 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:28 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:28 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:28 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:28 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:28 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:28 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:28 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:28 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:28 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:26:28 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:26:28 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:26:28 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:26:28 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:26:29 rivoml volumio[3379]: info: VolumeController::SetAlsaVolume- Oct 01 20:26:29 rivoml volumio[3379]: info: CoreStateMachine::pushState Oct 01 20:26:29 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:26:29 rivoml volumio[3379]: info: CoreCommandRouter::volumioPushState Oct 01 20:26:29 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output update for this device Oct 01 20:26:29 rivoml volumio[3379]: info: MRS: Pushing multiroomSync output Oct 01 20:26:29 rivoml volumio[3379]: info: Signalling Playback active due to playback status change Oct 01 20:26:29 rivoml volumio[3379]: info: Updating RAAT Signal Path Oct 01 20:26:29 rivoml volumio[3379]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 01 20:26:29 rivoml volumio[3379]: xcb_connection_has_error() returned true Oct 01 20:26:29 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:26:29 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:26:29 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:26:29 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:26:29 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:26:30 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:26:30 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:26:30 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:26:30 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:26:30 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:26:43 rivoml volumio[3379]: info: Retrieving Cloud Streaming UI Oct 01 20:26:43 rivoml volumio[3379]: info: Getting Tidal Cloud Configuration Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:26:43 rivoml volumio[3379]: info: Getting Qobuz Cloud Configuration Oct 01 20:26:43 rivoml volumio[3379]: info: Asking plugin for UI Config Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:26:43 rivoml volumio[3379]: info: Getting Spotify Cloud Configuration Oct 01 20:26:43 rivoml volumio[3379]: info: Asking plugin for UI Config Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:26:43 rivoml volumio[3379]: info: Saving Spotify Acccount Oct 01 20:26:43 rivoml volumio[3379]: info: Got it Oct 01 20:26:43 rivoml volumio[3379]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 01 20:26:43 rivoml volumio[3379]: info: Got Tidal Cloud Configuration Oct 01 20:26:43 rivoml volumio[3379]: info: Got it Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetBrowseSources Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetBrowseSources Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetBrowseSources Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 01 20:26:43 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 01 20:26:47 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 01 20:26:47 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:26:47 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:26:47 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:26:47 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:26:47 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:26:48 rivoml volumio[3379]: info: Disabling MyMusic plugin upnp Oct 01 20:26:48 rivoml sudo[5513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 01 20:26:48 rivoml sudo[5513]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:26:48 rivoml systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 01 20:26:48 rivoml volumio[3379]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 01 20:26:48 rivoml volumio[3379]: error: Upnp client error: Error: This socket has been ended by the other party Oct 01 20:26:48 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:26:48 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:26:48 rivoml volumio[3379]: info: Discovery: Getting this device information Oct 01 20:26:48 rivoml volumio[3379]: info: CoreCommandRouter::volumioGetState Oct 01 20:26:48 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:26:50 rivoml volumio[3379]: info: Enabling MyMusic plugin upnp Oct 01 20:26:50 rivoml volumio[3379]: info: Enabling plugin upnp Oct 01 20:26:50 rivoml volumio[3379]: info: Loading plugin "upnp"... Oct 01 20:26:50 rivoml volumio[3379]: info: [1727807210106] Starting Upmpd Daemon Oct 01 20:26:50 rivoml volumio[3379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 20:26:50 rivoml volumio[3379]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 20:26:50 rivoml volumio[3379]: Error: listen EADDRINUSE: address already in use :::6599 Oct 01 20:26:50 rivoml volumio[3379]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 01 20:26:50 rivoml volumio[3379]: at listenInCluster (net.js:1379:12) Oct 01 20:26:50 rivoml volumio[3379]: at Server.listen (net.js:1465:7) Oct 01 20:26:50 rivoml volumio[3379]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 01 20:26:50 rivoml volumio[3379]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 01 20:26:50 rivoml volumio[3379]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 01 20:26:50 rivoml volumio[3379]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 01 20:26:50 rivoml volumio[3379]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 01 20:26:50 rivoml volumio[3379]: code: 'EADDRINUSE', Oct 01 20:26:50 rivoml volumio[3379]: errno: -98, Oct 01 20:26:50 rivoml volumio[3379]: syscall: 'listen', Oct 01 20:26:50 rivoml volumio[3379]: address: '::', Oct 01 20:26:50 rivoml volumio[3379]: port: 6599 Oct 01 20:26:50 rivoml volumio[3379]: } Oct 01 20:26:50 rivoml volumio[3379]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 20:26:50 rivoml sudo[5528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-01 20:25 Oct 01 20:26:50 rivoml sudo[5528]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:47:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e08c3e210a64867536255c2503fb09"