-- Logs begin at Mon 2025-06-23 14:52:25 +07, end at Mon 2025-06-23 15:05:14 +07. -- Jun 23 15:04:01 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 23 15:04:02 rivoplus volumio[3190]: info: browseTIDALUri took 1057 milliseconds Jun 23 15:04:02 rivoplus volumio[3190]: info: Preload queue cleared Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/17977309 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/96718839 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/201310381 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/17977316 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/33910779 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/96718838 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/159563880 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/202133454 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/220339910 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/17977317 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/201310391 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/215223851 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/17977310 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/19882555 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/64944542 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/17977314 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/96718845 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/19882560 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/202133461 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/234059726 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/19882562 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/52535184 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/159563883 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/220339912 Jun 23 15:04:02 rivoplus volumio[3190]: info: Preloading song: tidal://song/201829420 Jun 23 15:04:02 rivoplus volumio[3190]: info: Exploding uri tidal://song/17977309 in service tidal Jun 23 15:04:02 rivoplus volumio[3190]: info: Exploding uri tidal://song/96718839 in service tidal Jun 23 15:04:02 rivoplus volumio[3190]: info: Exploding uri tidal://song/201310381 in service tidal Jun 23 15:04:02 rivoplus volumio[3190]: info: Exploding uri tidal://song/17977316 in service tidal Jun 23 15:04:02 rivoplus volumio[3190]: info: Exploding uri tidal://song/33910779 in service tidal Jun 23 15:04:02 rivoplus volumio[3190]: info: Exploding uri tidal://song/96718838 in service tidal Jun 23 15:04:02 rivoplus volumio[3190]: info: Exploding uri tidal://song/159563880 in service tidal Jun 23 15:04:02 rivoplus volumio[3190]: info: Exploding uri tidal://song/202133454 in service tidal Jun 23 15:04:02 rivoplus volumio[3190]: info: Exploding uri tidal://song/220339910 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/17977317 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/201310391 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/215223851 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 550 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/17977310 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 613 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/19882555 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 507 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 571 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/64944542 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 525 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/17977314 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/96718845 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 537 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/19882560 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 553 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/202133461 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/234059726 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 592 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/19882562 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 523 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/52535184 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 521 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/159563883 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/220339912 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: Exploding uri tidal://song/201829420 in service tidal Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 963 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 552 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 497 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 552 milliseconds Jun 23 15:04:03 rivoplus volumio[3190]: info: explodeTIDALUri took 516 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 972 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 546 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 582 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 565 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 973 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 978 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 537 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 557 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 634 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: explodeTIDALUri took 971 milliseconds Jun 23 15:04:04 rivoplus volumio[3190]: info: Preload queue cleared Jun 23 15:04:04 rivoplus volumio[3190]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 15:04:04 rivoplus volumio[3190]: info: CoreStateMachine::ClearQueue Jun 23 15:04:04 rivoplus volumio[3190]: info: CoreStateMachine::stop Jun 23 15:04:04 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 15:04:04 rivoplus volumio[3190]: info: CorePlayQueue::clearPlayQueue Jun 23 15:04:04 rivoplus volumio[3190]: info: CorePlayQueue::saveQueue Jun 23 15:04:04 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushQueue Jun 23 15:04:04 rivoplus volumio[3190]: info: CoreStateMachine::addQueueItems Jun 23 15:04:04 rivoplus volumio[3190]: info: CorePlayQueue::addQueueItems Jun 23 15:04:04 rivoplus volumio[3190]: info: Preload queue cleared Jun 23 15:04:04 rivoplus volumio[3190]: info: Adding Item to queue: tidal://playlist/e8e116a4-1cb2-4096-ad9f-b3f324031360 Jun 23 15:04:04 rivoplus volumio[3190]: info: Exploding uri tidal://playlist/e8e116a4-1cb2-4096-ad9f-b3f324031360 in service tidal Jun 23 15:04:05 rivoplus volumio[3190]: info: explodeTIDALUri took 935 milliseconds Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushQueue Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::saveQueue Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::updateTrackBlock Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::getTrackBlock Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPlay Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::play index 0 Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::stop Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::play index undefined Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::startPlaybackTimer Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:05 rivoplus volumio[3190]: info: [1750665845756] ControllerTidal::clearAddPlayTrack Jun 23 15:04:05 rivoplus volumio[3190]: info: Getting stream with soundQuality HI_RES Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 15:04:05 rivoplus volumio[3190]: STREAMING PROXY: Client dropped request, destroying Jun 23 15:04:05 rivoplus volumio[3190]: STREAMING PROXY: Error proxying request: Error: socket hang up Jun 23 15:04:05 rivoplus volumio[3190]: info: Jun 23 15:04:05 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Jun 23 15:04:05 rivoplus volumio[3190]: info: sendMpdCommand stop took 8 milliseconds Jun 23 15:04:05 rivoplus volumio[3190]: info: ControllerMpd::getState Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 15:04:05 rivoplus volumio[3190]: info: Jun 23 15:04:05 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Jun 23 15:04:05 rivoplus volumio[3190]: info: ControllerMpd::getState Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Jun 23 15:04:05 rivoplus volumio[3190]: info: Jun 23 15:04:05 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Jun 23 15:04:05 rivoplus volumio[3190]: info: ControllerMpd::getState Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Jun 23 15:04:05 rivoplus volumio[3190]: info: Jun 23 15:04:05 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Jun 23 15:04:05 rivoplus volumio[3190]: info: Ignoring MPD Status Update Jun 23 15:04:05 rivoplus volumio[3190]: info: sendMpdCommand status took 8 milliseconds Jun 23 15:04:05 rivoplus volumio[3190]: info: sendMpdCommand clear took 7 milliseconds Jun 23 15:04:05 rivoplus volumio[3190]: info: sendMpdCommand status took 5 milliseconds Jun 23 15:04:05 rivoplus volumio[3190]: info: sendMpdCommand status took 7 milliseconds Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/17977309&soundQuality=HI_RES" Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Jun 23 15:04:05 rivoplus volumio[3190]: info: ControllerMpd::pushState Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:05 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:05 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:05 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 23 15:04:05 rivoplus volumio[3190]: info: ControllerMpd::pushState Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:05 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:05 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:05 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:05 rivoplus volumio[3190]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 23 15:04:05 rivoplus volumio[3190]: info: ------------------------------ 52ms Jun 23 15:04:05 rivoplus volumio[3190]: info: ------------------------------ 51ms Jun 23 15:04:05 rivoplus volumio[3190]: info: Jun 23 15:04:05 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Jun 23 15:04:05 rivoplus volumio[3190]: info: Ignoring MPD Status Update Jun 23 15:04:05 rivoplus volumio[3190]: info: Jun 23 15:04:05 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Jun 23 15:04:05 rivoplus volumio[3190]: info: Ignoring MPD Status Update Jun 23 15:04:05 rivoplus volumio[3190]: error: updateQueue error: null Jun 23 15:04:05 rivoplus volumio[3190]: info: Jun 23 15:04:05 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Jun 23 15:04:05 rivoplus volumio[3190]: info: Ignoring MPD Status Update Jun 23 15:04:05 rivoplus volumio[3190]: info: ------------------------------ 59ms Jun 23 15:04:05 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 53 milliseconds Jun 23 15:04:05 rivoplus volumio[3190]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/17977309&soundQuality=HI_RES" took 53 milliseconds Jun 23 15:04:05 rivoplus volumio[3190]: info: ------------------------------ 11ms Jun 23 15:04:05 rivoplus volumio[3190]: info: ------------------------------ 10ms Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 23 15:04:05 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand play Jun 23 15:04:05 rivoplus volumio[3190]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 15:04:05 rivoplus volumio[3190]: info: ------------------------------ 74ms Jun 23 15:04:05 rivoplus volumio[3190]: info: Jun 23 15:04:05 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Jun 23 15:04:05 rivoplus volumio[3190]: info: Ignoring MPD Status Update Jun 23 15:04:05 rivoplus volumio[3190]: STREAMING PROXY: Handling url /?data=tidal://song/17977309&soundQuality=HI_RES Jun 23 15:04:05 rivoplus volumio[3190]: info: Jun 23 15:04:05 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Jun 23 15:04:05 rivoplus volumio[3190]: info: Ignoring MPD Status Update Jun 23 15:04:05 rivoplus volumio[3190]: info: ------------------------------ 46ms Jun 23 15:04:05 rivoplus volumio[3190]: info: sendMpdCommand play took 40 milliseconds Jun 23 15:04:05 rivoplus volumio[3190]: info: ------------------------------ 38ms Jun 23 15:04:05 rivoplus volumio[3190]: info: ------------------------------ 37ms Jun 23 15:04:05 rivoplus volumio[3190]: info: Executing endpoint getStreamUrltidal Jun 23 15:04:05 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 23 15:04:05 rivoplus volumio[3190]: info: MCU Signalled Playback Inactive Jun 23 15:04:06 rivoplus volumio[3190]: info: getStreamUrl took 537 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidkOWRmYWNlMDFiNThhODQzOGYwYTFmZTM0YmNkOWFhMF82Mi5tcDQ/0.flac?token=1750669447~ZjZlYzA5NTJiMDVjODYwODEwODgwNzRiNjg0ZTU2MjVjZDMxMjEyNg== Jun 23 15:04:06 rivoplus volumio[3190]: STREAMING PROXY: Response: 200, length: 55790469 Jun 23 15:04:06 rivoplus volumio[3190]: info: Jun 23 15:04:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::getState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Jun 23 15:04:06 rivoplus volumio[3190]: info: Jun 23 15:04:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::getState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Jun 23 15:04:06 rivoplus volumio[3190]: info: Jun 23 15:04:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::getState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand status took 134 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 15:04:06 rivoplus volumio[3190]: info: Jun 23 15:04:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::getState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Jun 23 15:04:06 rivoplus volumio[3190]: info: Jun 23 15:04:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::getState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Jun 23 15:04:06 rivoplus volumio[3190]: info: Jun 23 15:04:06 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::getState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand status took 100 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand status took 76 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand status took 8 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand status took 6 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":310,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"887 Kbps","isStreaming":false,"title":"17977309&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17977309&soundQuality=HI_RES","trackType":"tidal"} Jun 23 15:04:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus stop Jun 23 15:04:06 rivoplus volumio[3190]: info: ------------------------------ 161ms Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand status took 15 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":310,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"960 Kbps","isStreaming":false,"title":"17977309&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17977309&soundQuality=HI_RES","trackType":"tidal"} Jun 23 15:04:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Jun 23 15:04:06 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":310,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"961 Kbps","isStreaming":false,"title":"17977309&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17977309&soundQuality=HI_RES","trackType":"tidal"} Jun 23 15:04:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Jun 23 15:04:06 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":310,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"961 Kbps","isStreaming":false,"title":"17977309&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17977309&soundQuality=HI_RES","trackType":"tidal"} Jun 23 15:04:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Jun 23 15:04:06 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":310,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"961 Kbps","isStreaming":false,"title":"17977309&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17977309&soundQuality=HI_RES","trackType":"tidal"} Jun 23 15:04:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Jun 23 15:04:06 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: ------------------------------ 358ms Jun 23 15:04:06 rivoplus volumio[3190]: info: ------------------------------ 312ms Jun 23 15:04:06 rivoplus volumio[3190]: info: ------------------------------ 231ms Jun 23 15:04:06 rivoplus volumio[3190]: info: ------------------------------ 230ms Jun 23 15:04:06 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 218 milliseconds Jun 23 15:04:06 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Jun 23 15:04:06 rivoplus volumio[3190]: info: ControllerMpd::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Jun 23 15:04:06 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":310,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"961 Kbps","isStreaming":false,"title":"17977309&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17977309&soundQuality=HI_RES","trackType":"tidal"} Jun 23 15:04:06 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Jun 23 15:04:06 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreStateMachine::pushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Jun 23 15:04:06 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Jun 23 15:04:06 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Jun 23 15:04:06 rivoplus volumio[3190]: info: ------------------------------ 324ms Jun 23 15:04:07 rivoplus volumio[3190]: info: Signalling Playback active due to playback status change Jun 23 15:04:07 rivoplus volumio[3190]: info: MCU Signalled Playback Active Jun 23 15:04:08 rivoplus volumio[3190]: info: Executing endpoint metavolumio Jun 23 15:04:08 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 23 15:04:08 rivoplus volumio[3190]: info: Executing endpoint metavolumio Jun 23 15:04:08 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 23 15:04:08 rivoplus volumio[3190]: info: Executing endpoint metavolumio Jun 23 15:04:08 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 23 15:04:10 rivoplus volumio[3190]: STREAMING PROXY: Client dropped request, destroying Jun 23 15:04:15 rivoplus volumio[3190]: error: Failed request for metavolumio API Jun 23 15:04:15 rivoplus volumio[3190]: error: Failed request for metavolumio API Jun 23 15:04:25 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 15:04:25 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 15:04:25 rivoplus volumio[3190]: info: Discovery: Getting this device information Jun 23 15:04:25 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:25 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 15:04:38 rivoplus ntpd[3437]: Soliciting pool server 103.70.115.65 Jun 23 15:04:44 rivoplus ntpd[3437]: Soliciting pool server 103.184.124.254 Jun 23 15:04:44 rivoplus ntpd[3437]: Soliciting pool server 103.70.115.65 Jun 23 15:04:45 rivoplus ntpd[3437]: Soliciting pool server 27.71.27.209 Jun 23 15:04:55 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 15:04:55 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 15:04:55 rivoplus volumio[3190]: info: Discovery: Getting this device information Jun 23 15:04:55 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Jun 23 15:04:55 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 15:05:13 rivoplus volumio[3190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 15:05:13 rivoplus volumio[3190]: Error: connect ETIMEDOUT 151.101.130.79:443 Jun 23 15:05:13 rivoplus volumio[3190]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 23 15:05:13 rivoplus volumio[3190]: errno: -110, Jun 23 15:05:13 rivoplus volumio[3190]: code: 'ETIMEDOUT', Jun 23 15:05:13 rivoplus volumio[3190]: syscall: 'connect', Jun 23 15:05:13 rivoplus volumio[3190]: address: '151.101.130.79', Jun 23 15:05:13 rivoplus volumio[3190]: port: 443 Jun 23 15:05:13 rivoplus volumio[3190]: } Jun 23 15:05:13 rivoplus volumio[3190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 15:05:14 rivoplus sudo[7347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-23 15:04 Jun 23 15:05:14 rivoplus sudo[7347]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:41:30 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="e2256d79dd6d0ae59eb47f10e57a5e95"