-- Logs begin at Thu 2025-04-24 03:27:32 CEST, end at Fri 2025-04-25 18:06:49 CEST. -- Apr 25 18:05:02 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 25 18:05:02 rivoplus volumio[2181]: info: browseTIDALUri took 398 milliseconds Apr 25 18:05:02 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896355 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896356 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896357 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896358 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896359 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896360 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896361 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896362 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896363 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896364 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896365 Apr 25 18:05:02 rivoplus volumio[2181]: info: Preloading song: tidal://song/201896366 Apr 25 18:05:02 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896355 in service tidal Apr 25 18:05:02 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896356 in service tidal Apr 25 18:05:02 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896357 in service tidal Apr 25 18:05:02 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896358 in service tidal Apr 25 18:05:02 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896359 in service tidal Apr 25 18:05:02 rivoplus volumio[2181]: info: explodeTIDALUri took 243 milliseconds Apr 25 18:05:02 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896360 in service tidal Apr 25 18:05:02 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896361 in service tidal Apr 25 18:05:02 rivoplus volumio[2181]: info: explodeTIDALUri took 301 milliseconds Apr 25 18:05:02 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896362 in service tidal Apr 25 18:05:02 rivoplus volumio[2181]: info: explodeTIDALUri took 283 milliseconds Apr 25 18:05:02 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896363 in service tidal Apr 25 18:05:03 rivoplus volumio[2181]: info: Executing endpoint getSimilarAlbums Apr 25 18:05:03 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 25 18:05:03 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896364 in service tidal Apr 25 18:05:03 rivoplus volumio[2181]: info: Executing endpoint metavolumio Apr 25 18:05:03 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 18:05:03 rivoplus volumio[2181]: info: Executing endpoint metavolumio Apr 25 18:05:03 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 18:05:03 rivoplus volumio[2181]: info: explodeTIDALUri took 352 milliseconds Apr 25 18:05:03 rivoplus volumio[2181]: info: explodeTIDALUri took 306 milliseconds Apr 25 18:05:03 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896365 in service tidal Apr 25 18:05:03 rivoplus volumio[2181]: info: explodeTIDALUri took 266 milliseconds Apr 25 18:05:03 rivoplus volumio[2181]: info: Exploding uri tidal://song/201896366 in service tidal Apr 25 18:05:03 rivoplus volumio[2181]: info: explodeTIDALUri took 267 milliseconds Apr 25 18:05:03 rivoplus volumio[2181]: info: explodeTIDALUri took 413 milliseconds Apr 25 18:05:03 rivoplus volumio[2181]: info: explodeTIDALUri took 308 milliseconds Apr 25 18:05:03 rivoplus volumio[2181]: info: explodeTIDALUri took 356 milliseconds Apr 25 18:05:03 rivoplus volumio[2181]: info: explodeTIDALUri took 345 milliseconds Apr 25 18:05:03 rivoplus volumio[2181]: info: explodeTIDALUri took 352 milliseconds Apr 25 18:05:10 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:05:10 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:05:10 rivoplus volumio[2181]: info: Discovery: Getting this device information Apr 25 18:05:10 rivoplus volumio[2181]: info: CoreCommandRouter::volumioGetState Apr 25 18:05:10 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:05:13 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::ClearQueue Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::stop Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::updateTrackBlock Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrackBlock Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::stPlaybackTimer Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:13 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:13 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::serviceStop Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::serviceStop Apr 25 18:05:13 rivoplus volumio[2181]: info: [1745597113540] ControllerTidal::stop Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 18:05:13 rivoplus volumio[2181]: info: ControllerMpd::stop Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::clearPlayQueue Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::saveQueue Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushQueue Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::addQueueItems Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::addQueueItems Apr 25 18:05:13 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896355 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896355 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896356 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896356 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896357 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896357 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896358 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896358 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896359 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896359 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896360 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896360 Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushQueue Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::saveQueue Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::updateTrackBlock Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrackBlock Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPlay Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::play index 5 Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::addQueueItems Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::addQueueItems Apr 25 18:05:13 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896361 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896361 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896362 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896362 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896363 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896363 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896364 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896364 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896365 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896365 Apr 25 18:05:13 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896366 Apr 25 18:05:13 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896366 Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::stop Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushQueue Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::saveQueue Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::play index undefined Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::updateTrackBlock Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrackBlock Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::startPlaybackTimer Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:13 rivoplus volumio[2181]: info: [1745597113607] ControllerTidal::clearAddPlayTrack Apr 25 18:05:13 rivoplus volumio[2181]: info: Getting stream with soundQuality HI_RES Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 18:05:13 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:13 rivoplus volumio[2181]: info: Apr 25 18:05:13 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:13 rivoplus volumio[2181]: info: sendMpdCommand stop took 109 milliseconds Apr 25 18:05:13 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:13 rivoplus volumio[2181]: info: sendMpdCommand stop took 42 milliseconds Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand clear Apr 25 18:05:13 rivoplus volumio[2181]: info: Apr 25 18:05:13 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:13 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:13 rivoplus volumio[2181]: info: Apr 25 18:05:13 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:13 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:13 rivoplus volumio[2181]: info: Apr 25 18:05:13 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:13 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:13 rivoplus volumio[2181]: info: sendMpdCommand status took 17 milliseconds Apr 25 18:05:13 rivoplus volumio[2181]: info: sendMpdCommand clear took 15 milliseconds Apr 25 18:05:13 rivoplus volumio[2181]: info: sendMpdCommand status took 11 milliseconds Apr 25 18:05:13 rivoplus volumio[2181]: info: sendMpdCommand status took 8 milliseconds Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/201896360&soundQuality=HI_RES" Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:13 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:13 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:13 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:13 rivoplus volumio[2181]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 25 18:05:13 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:13 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:13 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:13 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:13 rivoplus volumio[2181]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 25 18:05:13 rivoplus volumio[2181]: info: ------------------------------ 64ms Apr 25 18:05:13 rivoplus volumio[2181]: info: ------------------------------ 63ms Apr 25 18:05:13 rivoplus volumio[2181]: info: Apr 25 18:05:13 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:13 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:13 rivoplus volumio[2181]: info: Apr 25 18:05:13 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:13 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:13 rivoplus volumio[2181]: error: updateQueue error: null Apr 25 18:05:13 rivoplus volumio[2181]: info: Apr 25 18:05:13 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:13 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:13 rivoplus volumio[2181]: info: ------------------------------ 70ms Apr 25 18:05:13 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 64 milliseconds Apr 25 18:05:13 rivoplus volumio[2181]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/201896360&soundQuality=HI_RES" took 63 milliseconds Apr 25 18:05:13 rivoplus volumio[2181]: info: ------------------------------ 13ms Apr 25 18:05:13 rivoplus volumio[2181]: info: ------------------------------ 11ms Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 18:05:13 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand play Apr 25 18:05:13 rivoplus volumio[2181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 25 18:05:13 rivoplus volumio[2181]: info: ------------------------------ 98ms Apr 25 18:05:13 rivoplus volumio[2181]: info: Apr 25 18:05:13 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:13 rivoplus volumio[2181]: STREAMING PROXY: Handling url /?data=tidal://song/201896360&soundQuality=HI_RES Apr 25 18:05:13 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:13 rivoplus volumio[2181]: info: Apr 25 18:05:13 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:13 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:13 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:13 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:13 rivoplus volumio[2181]: info: ------------------------------ 38ms Apr 25 18:05:13 rivoplus volumio[2181]: info: sendMpdCommand play took 30 milliseconds Apr 25 18:05:13 rivoplus volumio[2181]: info: ------------------------------ 26ms Apr 25 18:05:13 rivoplus volumio[2181]: info: ------------------------------ 25ms Apr 25 18:05:13 rivoplus volumio[2181]: info: Executing endpoint getStreamUrltidal Apr 25 18:05:13 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 25 18:05:14 rivoplus volumio[2181]: info: getStreamUrl took 526 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic3OWVkZGZiMGRmMDE4MjYyZjViMjcyNzFkMTY1ZThiZV82MS5tcDQ/0.flac?Expires=1745600714&Signature=aJ6C7aGKJLHelPugiJ9jDGwqSCXV8T~eQkMd50XjiQzApkZ78qqNXX7ON~-o7G5HzlCSp153J9g21bbnHPVhvfL7I-lNbbSmXCFkvjSLpu4Tuq~yuwyTe~xUDdg~88rUylDzQolIAFCyu1vlwDxjSiYAjd8mWHfU5mepyQYwpbXxDJhnzEg01e~j04YH28hvwLsRGoBhbR7-x~nORb~w0Lmdj2DLoV6kIvgOfSUbDgJFqm39Iskw9A5QXFpg~bbiMOGFyxIDES0ENREf9Tkoljr-ypc02xS3nlWJiChudBfKCsQ5M7qkZXn~R2HhYIs-6CcFqftigTziGTIuOHo4Cg__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 25 18:05:14 rivoplus volumio[2181]: STREAMING PROXY: Response: 200, length: 24725952 Apr 25 18:05:14 rivoplus volumio[2181]: info: Apr 25 18:05:14 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:14 rivoplus volumio[2181]: info: Apr 25 18:05:14 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:14 rivoplus volumio[2181]: info: Apr 25 18:05:14 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand status took 28 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:14 rivoplus volumio[2181]: info: Apr 25 18:05:14 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand status took 32 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand status took 30 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:14 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896360&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896360&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:14 rivoplus volumio[2181]: verbose: CURRENT POSITION 5 Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:05:14 rivoplus volumio[2181]: info: ------------------------------ 44ms Apr 25 18:05:14 rivoplus volumio[2181]: info: Apr 25 18:05:14 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:14 rivoplus volumio[2181]: info: Apr 25 18:05:14 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand status took 15 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand status took 5 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand status took 4 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:14 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896360&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896360&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:14 rivoplus volumio[2181]: verbose: CURRENT POSITION 5 Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:14 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:14 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896360&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896360&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:14 rivoplus volumio[2181]: verbose: CURRENT POSITION 5 Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:14 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: ------------------------------ 125ms Apr 25 18:05:14 rivoplus volumio[2181]: info: ------------------------------ 125ms Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 78 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 77 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 76 milliseconds Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:14 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:14 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896360&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896360&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:14 rivoplus volumio[2181]: verbose: CURRENT POSITION 5 Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:14 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:14 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896360&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896360&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:14 rivoplus volumio[2181]: verbose: CURRENT POSITION 5 Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:14 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:14 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896360&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896360&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:14 rivoplus volumio[2181]: verbose: CURRENT POSITION 5 Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:14 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:14 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:14 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:14 rivoplus volumio[2181]: info: ------------------------------ 184ms Apr 25 18:05:14 rivoplus volumio[2181]: info: ------------------------------ 175ms Apr 25 18:05:14 rivoplus volumio[2181]: info: ------------------------------ 173ms Apr 25 18:05:14 rivoplus volumio[2181]: info: Signalling Playback active due to playback status change Apr 25 18:05:14 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:14 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:14 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:14 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:14 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:14 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:14 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:14 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:14 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:14 rivoplus volumio[2181]: info: MCU Signalled Playback Active Apr 25 18:05:14 rivoplus volumio[2181]: info: MCU Signalled Sleep Mode Disabled Apr 25 18:05:15 rivoplus sudo[8214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Apr 25 18:05:15 rivoplus sudo[8214]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 18:05:15 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Apr 25 18:05:15 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Apr 25 18:05:15 rivoplus sudo[8214]: pam_unix(sudo:session): session closed for user root Apr 25 18:05:15 rivoplus sudo[8235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Apr 25 18:05:15 rivoplus sudo[8235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 18:05:15 rivoplus sudo[8235]: pam_unix(sudo:session): session closed for user root Apr 25 18:05:15 rivoplus volumio[2181]: STREAMING PROXY: Client dropped request, destroying Apr 25 18:05:17 rivoplus volumio[2181]: info: Executing endpoint metavolumio Apr 25 18:05:17 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 18:05:17 rivoplus volumio[2181]: info: Executing endpoint metavolumio Apr 25 18:05:17 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 18:05:17 rivoplus volumio[2181]: info: Executing endpoint metavolumio Apr 25 18:05:17 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 18:05:18 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::ClearQueue Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::stop Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::stPlaybackTimer Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::updateTrackBlock Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::getTrackBlock Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::serviceStop Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 5 Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::serviceStop Apr 25 18:05:18 rivoplus volumio[2181]: info: [1745597118669] ControllerTidal::stop Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 18:05:18 rivoplus volumio[2181]: info: ControllerMpd::stop Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::clearPlayQueue Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::saveQueue Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushQueue Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::addQueueItems Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::addQueueItems Apr 25 18:05:18 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896355 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896355 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896356 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896356 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896357 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896357 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896358 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896358 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896359 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896359 Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushQueue Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::saveQueue Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::updateTrackBlock Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::getTrackBlock Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPlay Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::play index 4 Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::addQueueItems Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::addQueueItems Apr 25 18:05:18 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896360 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896360 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896361 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896361 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896362 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896362 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896363 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896363 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896364 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896364 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896365 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896365 Apr 25 18:05:18 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896366 Apr 25 18:05:18 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896366 Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::stop Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushQueue Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::saveQueue Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::play index undefined Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::updateTrackBlock Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::getTrackBlock Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::startPlaybackTimer Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:18 rivoplus volumio[2181]: info: [1745597118730] ControllerTidal::clearAddPlayTrack Apr 25 18:05:18 rivoplus volumio[2181]: info: Getting stream with soundQuality HI_RES Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 18:05:18 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:18 rivoplus volumio[2181]: info: Apr 25 18:05:18 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:18 rivoplus volumio[2181]: info: sendMpdCommand stop took 82 milliseconds Apr 25 18:05:18 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:18 rivoplus volumio[2181]: info: sendMpdCommand stop took 22 milliseconds Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand clear Apr 25 18:05:18 rivoplus volumio[2181]: info: Apr 25 18:05:18 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:18 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:18 rivoplus volumio[2181]: info: sendMpdCommand status took 5 milliseconds Apr 25 18:05:18 rivoplus volumio[2181]: info: sendMpdCommand clear took 3 milliseconds Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/201896359&soundQuality=HI_RES" Apr 25 18:05:18 rivoplus volumio[2181]: info: Apr 25 18:05:18 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:18 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:18 rivoplus volumio[2181]: info: Apr 25 18:05:18 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:18 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:18 rivoplus volumio[2181]: error: updateQueue error: null Apr 25 18:05:18 rivoplus volumio[2181]: info: Apr 25 18:05:18 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:18 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:18 rivoplus volumio[2181]: info: ------------------------------ 14ms Apr 25 18:05:18 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 25 18:05:18 rivoplus volumio[2181]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/201896359&soundQuality=HI_RES" took 11 milliseconds Apr 25 18:05:18 rivoplus volumio[2181]: info: sendMpdCommand status took 8 milliseconds Apr 25 18:05:18 rivoplus volumio[2181]: info: sendMpdCommand status took 6 milliseconds Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand play Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:18 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:18 rivoplus volumio[2181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 25 18:05:18 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:18 rivoplus volumio[2181]: STREAMING PROXY: Handling url /?data=tidal://song/201896359&soundQuality=HI_RES Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:18 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 25 18:05:18 rivoplus volumio[2181]: verbose: CURRENT POSITION 4 Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService stop Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:18 rivoplus volumio[2181]: info: No code Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:18 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:18 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:18 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 25 18:05:18 rivoplus volumio[2181]: verbose: CURRENT POSITION 4 Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService stop Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:18 rivoplus volumio[2181]: info: No code Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:18 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:18 rivoplus volumio[2181]: info: ------------------------------ 73ms Apr 25 18:05:18 rivoplus volumio[2181]: info: ------------------------------ 96ms Apr 25 18:05:18 rivoplus volumio[2181]: info: ------------------------------ 96ms Apr 25 18:05:18 rivoplus volumio[2181]: info: Apr 25 18:05:18 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:18 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:18 rivoplus volumio[2181]: info: Apr 25 18:05:18 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:18 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:18 rivoplus volumio[2181]: info: ------------------------------ 105ms Apr 25 18:05:18 rivoplus volumio[2181]: info: sendMpdCommand play took 99 milliseconds Apr 25 18:05:18 rivoplus volumio[2181]: info: ------------------------------ 7ms Apr 25 18:05:18 rivoplus volumio[2181]: info: ------------------------------ 5ms Apr 25 18:05:18 rivoplus volumio[2181]: info: Signalling Playback active due to playback status change Apr 25 18:05:18 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:18 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:18 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:18 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:18 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:18 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:18 rivoplus volumio[2181]: info: MCU Signalled Playback Inactive Apr 25 18:05:18 rivoplus volumio[2181]: info: Executing endpoint getStreamUrltidal Apr 25 18:05:18 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 25 18:05:18 rivoplus volumio[2181]: info: MCU Signalled Playback Active Apr 25 18:05:19 rivoplus volumio[2181]: info: getStreamUrl took 742 milliseconds Apr 25 18:05:19 rivoplus volumio[2181]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEiczYWMxOGE2MjFkNmRmMGZhYWU0ZWJkYTRlODQ1ZjVkOF82MS5tcDQ/0.flac?Expires=1745600719&Signature=ofDsUPXd3dVGhSjjQjY9d3PdMKN6Wnaq5KHFarqv3Ql1kKzJ95wc8bFPUME5jTRhsWrwEvy7AR4n7PfahlaGg02Xd8Hz62E8j33Huy90uElyU~kiJe061qpaAoC-J856nB28r-XY8u0Pw2Dpp3UXTR8qg-6rI9RLOGGoKEQQ5T9n3Nyd6XQQRDe8sGsCrFpEkUnHxLuavalvJF5jABtf2ZX9hr19ThQ-ngKYrw4d6TWzAZ3H8y1K85F5u1yY79TllrOwVmhJx1vbVVs3wewcADcVu~9F5VuGPy4Nrdnj59hn3OI9QlhNq3CuspQuHa5J7~cDT-bHIKNIfF1tCcEOFw__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 25 18:05:20 rivoplus volumio[2181]: STREAMING PROXY: Response: 200, length: 23264442 Apr 25 18:05:20 rivoplus volumio[2181]: info: Apr 25 18:05:20 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:20 rivoplus volumio[2181]: info: Apr 25 18:05:20 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:20 rivoplus volumio[2181]: info: Apr 25 18:05:20 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:20 rivoplus volumio[2181]: info: Apr 25 18:05:20 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand status took 34 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand status took 33 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand status took 34 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand status took 5 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:20 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896359&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896359&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:20 rivoplus volumio[2181]: verbose: CURRENT POSITION 4 Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:20 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896359&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896359&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:20 rivoplus volumio[2181]: verbose: CURRENT POSITION 4 Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:20 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: ------------------------------ 71ms Apr 25 18:05:20 rivoplus volumio[2181]: info: ------------------------------ 91ms Apr 25 18:05:20 rivoplus volumio[2181]: info: Apr 25 18:05:20 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:20 rivoplus volumio[2181]: info: Apr 25 18:05:20 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 56 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 56 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand status took 6 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand status took 3 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:20 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896359&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896359&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:20 rivoplus volumio[2181]: verbose: CURRENT POSITION 4 Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:20 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:20 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896359&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896359&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:20 rivoplus volumio[2181]: verbose: CURRENT POSITION 4 Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:20 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: ------------------------------ 167ms Apr 25 18:05:20 rivoplus volumio[2181]: info: ------------------------------ 138ms Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 110 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 109 milliseconds Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:20 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:20 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1075,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"355 Kbps","isStreaming":false,"title":"201896359&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896359&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:20 rivoplus volumio[2181]: verbose: CURRENT POSITION 4 Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:20 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:20 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1075,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"355 Kbps","isStreaming":false,"title":"201896359&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896359&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:20 rivoplus volumio[2181]: verbose: CURRENT POSITION 4 Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:20 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:20 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:20 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:20 rivoplus volumio[2181]: info: ------------------------------ 184ms Apr 25 18:05:20 rivoplus volumio[2181]: info: ------------------------------ 184ms Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:20 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:21 rivoplus volumio[2181]: STREAMING PROXY: Client dropped request, destroying Apr 25 18:05:27 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::ClearQueue Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::stop Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::stPlaybackTimer Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::updateTrackBlock Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrackBlock Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:27 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:27 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::serviceStop Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 4 Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::serviceStop Apr 25 18:05:27 rivoplus volumio[2181]: info: [1745597127648] ControllerTidal::stop Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 18:05:27 rivoplus volumio[2181]: info: ControllerMpd::stop Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::clearPlayQueue Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::saveQueue Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushQueue Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::addQueueItems Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::addQueueItems Apr 25 18:05:27 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896355 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896355 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896356 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896356 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896357 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896357 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896358 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896358 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896359 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896359 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896360 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896360 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896361 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896361 Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushQueue Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::saveQueue Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::updateTrackBlock Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrackBlock Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPlay Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::play index 6 Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::addQueueItems Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::addQueueItems Apr 25 18:05:27 rivoplus volumio[2181]: info: Preload queue cleared Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896362 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896362 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896363 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896363 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896364 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896364 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896365 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896365 Apr 25 18:05:27 rivoplus volumio[2181]: info: Adding Item to queue: tidal://song/201896366 Apr 25 18:05:27 rivoplus volumio[2181]: info: Using cached record of: tidal://song/201896366 Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::stop Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushQueue Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::saveQueue Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::play index undefined Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::updateTrackBlock Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrackBlock Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::startPlaybackTimer Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:27 rivoplus volumio[2181]: info: [1745597127714] ControllerTidal::clearAddPlayTrack Apr 25 18:05:27 rivoplus volumio[2181]: info: Getting stream with soundQuality HI_RES Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 18:05:27 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:27 rivoplus volumio[2181]: info: Apr 25 18:05:27 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:27 rivoplus volumio[2181]: info: sendMpdCommand stop took 84 milliseconds Apr 25 18:05:27 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:27 rivoplus volumio[2181]: info: sendMpdCommand stop took 19 milliseconds Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand clear Apr 25 18:05:27 rivoplus volumio[2181]: info: Apr 25 18:05:27 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:27 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:27 rivoplus volumio[2181]: info: Apr 25 18:05:27 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:27 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:27 rivoplus volumio[2181]: info: Apr 25 18:05:27 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:27 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:27 rivoplus volumio[2181]: info: sendMpdCommand status took 11 milliseconds Apr 25 18:05:27 rivoplus volumio[2181]: info: sendMpdCommand clear took 10 milliseconds Apr 25 18:05:27 rivoplus volumio[2181]: info: sendMpdCommand status took 7 milliseconds Apr 25 18:05:27 rivoplus volumio[2181]: info: sendMpdCommand status took 5 milliseconds Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/201896361&soundQuality=HI_RES" Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:27 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:27 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:27 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:27 rivoplus volumio[2181]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 25 18:05:27 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:27 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:27 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:27 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:27 rivoplus volumio[2181]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 25 18:05:27 rivoplus volumio[2181]: info: ------------------------------ 54ms Apr 25 18:05:27 rivoplus volumio[2181]: info: ------------------------------ 52ms Apr 25 18:05:27 rivoplus volumio[2181]: info: Apr 25 18:05:27 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:27 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:27 rivoplus volumio[2181]: info: Apr 25 18:05:27 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:27 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:27 rivoplus volumio[2181]: error: updateQueue error: null Apr 25 18:05:27 rivoplus volumio[2181]: info: Apr 25 18:05:27 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:27 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:27 rivoplus volumio[2181]: info: ------------------------------ 58ms Apr 25 18:05:27 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 54 milliseconds Apr 25 18:05:27 rivoplus volumio[2181]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/201896361&soundQuality=HI_RES" took 53 milliseconds Apr 25 18:05:27 rivoplus volumio[2181]: info: ------------------------------ 9ms Apr 25 18:05:27 rivoplus volumio[2181]: info: ------------------------------ 8ms Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 18:05:27 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand play Apr 25 18:05:27 rivoplus volumio[2181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 25 18:05:27 rivoplus volumio[2181]: info: ------------------------------ 75ms Apr 25 18:05:27 rivoplus volumio[2181]: info: Apr 25 18:05:27 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:27 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:27 rivoplus volumio[2181]: STREAMING PROXY: Handling url /?data=tidal://song/201896361&soundQuality=HI_RES Apr 25 18:05:27 rivoplus volumio[2181]: info: Apr 25 18:05:27 rivoplus volumio[2181]: ---------------------------- MPD announces system playlist update Apr 25 18:05:27 rivoplus volumio[2181]: info: Ignoring MPD Status Update Apr 25 18:05:27 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:27 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:27 rivoplus volumio[2181]: info: ------------------------------ 30ms Apr 25 18:05:27 rivoplus volumio[2181]: info: sendMpdCommand play took 26 milliseconds Apr 25 18:05:27 rivoplus volumio[2181]: info: ------------------------------ 23ms Apr 25 18:05:27 rivoplus volumio[2181]: info: ------------------------------ 22ms Apr 25 18:05:27 rivoplus volumio[2181]: info: Executing endpoint getStreamUrltidal Apr 25 18:05:27 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 25 18:05:27 rivoplus volumio[2181]: info: MCU Signalled Playback Inactive Apr 25 18:05:28 rivoplus volumio[2181]: info: getStreamUrl took 501 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEiczZTJlZGIxYjQ4MTI4ZWI1ZmY4OTkwOTNlNmEwMDViMV82MS5tcDQ/0.flac?Expires=1745600728&Signature=ablorN90DZFOUveXewJPj2iRf14oYrDb8CH2gqGz24BAHyLZTfAk8k5liH0VMjjPEVWSa~t39vGm1s9TsujKcUxIKS4qVids5v~dbyNVGbdtsZGUY7I6pA-~Kk5Fxkb~~s1VkGW6cIkpSzbgH6h~hcuVxOjdVkjDF~YKdXS1cTYmtcUGVlsGQefhwLVg-AT5TrpV5GLEC8IQNlk~ZvScRewA~ZS5No8~Zv6Kewho0mdRfWNB-6ZimNgzZy8f~62lCCJF2SCFKdVU4Cq5t13AYI5CLsN-1XoaIqJlqUQpqIvQ402aIQR-3GQMjcHGtF8M7sW9Gm0Fobxrt-regmtsBA__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 25 18:05:28 rivoplus volumio[2181]: STREAMING PROXY: Response: 200, length: 29561005 Apr 25 18:05:28 rivoplus volumio[2181]: info: Apr 25 18:05:28 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:28 rivoplus volumio[2181]: info: Apr 25 18:05:28 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:28 rivoplus volumio[2181]: info: Apr 25 18:05:28 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:28 rivoplus volumio[2181]: info: Apr 25 18:05:28 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand status took 30 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand status took 29 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand status took 28 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:28 rivoplus volumio[2181]: info: Apr 25 18:05:28 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:28 rivoplus volumio[2181]: info: Apr 25 18:05:28 rivoplus volumio[2181]: ---------------------------- MPD announces state update: player Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::getState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand status Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand status took 14 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand status took 7 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand status took 5 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseState Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:28 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896361&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896361&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:28 rivoplus volumio[2181]: verbose: CURRENT POSITION 6 Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus stop Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:28 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896361&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896361&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:28 rivoplus volumio[2181]: verbose: CURRENT POSITION 6 Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:28 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:28 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896361&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896361&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:28 rivoplus volumio[2181]: verbose: CURRENT POSITION 6 Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:28 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: ------------------------------ 93ms Apr 25 18:05:28 rivoplus volumio[2181]: info: ------------------------------ 128ms Apr 25 18:05:28 rivoplus volumio[2181]: info: ------------------------------ 127ms Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 83 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 81 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: info: sendMpdCommand playlistinfo took 82 milliseconds Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:28 rivoplus volumio[2181]: verbose: ControllerMpd::parseTrackInfo Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:28 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896361&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896361&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:28 rivoplus volumio[2181]: verbose: CURRENT POSITION 6 Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:28 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:28 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896361&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896361&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:28 rivoplus volumio[2181]: verbose: CURRENT POSITION 6 Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:28 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: ControllerMpd::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::servicePushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CorePlayQueue::getTrack 6 Apr 25 18:05:28 rivoplus volumio[2181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"201896361&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/201896361&soundQuality=HI_RES","trackType":"tidal"} Apr 25 18:05:28 rivoplus volumio[2181]: verbose: CURRENT POSITION 6 Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState stateService play Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::syncState currentStatus play Apr 25 18:05:28 rivoplus volumio[2181]: info: Received an update from plugin. extracting info from payload Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreStateMachine::pushState Apr 25 18:05:28 rivoplus volumio[2181]: info: CoreCommandRouter::volumioPushState Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output update for this device Apr 25 18:05:28 rivoplus volumio[2181]: info: MRS: Pushing multiroomSync output Apr 25 18:05:28 rivoplus volumio[2181]: info: ------------------------------ 211ms Apr 25 18:05:28 rivoplus volumio[2181]: info: ------------------------------ 205ms Apr 25 18:05:28 rivoplus volumio[2181]: info: ------------------------------ 201ms Apr 25 18:05:28 rivoplus volumio[2181]: info: Signalling Playback active due to playback status change Apr 25 18:05:28 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:28 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:28 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:28 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:28 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:28 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:28 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:28 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:28 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:29 rivoplus volumio[2181]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 25 18:05:29 rivoplus volumio[2181]: info: MCU Signalled Playback Active Apr 25 18:05:29 rivoplus volumio[2181]: STREAMING PROXY: Client dropped request, destroying Apr 25 18:05:40 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:05:40 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:05:40 rivoplus volumio[2181]: info: Discovery: Getting this device information Apr 25 18:05:40 rivoplus volumio[2181]: info: CoreCommandRouter::volumioGetState Apr 25 18:05:40 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:06:10 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:06:10 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:06:10 rivoplus volumio[2181]: info: Discovery: Getting this device information Apr 25 18:06:10 rivoplus volumio[2181]: info: CoreCommandRouter::volumioGetState Apr 25 18:06:10 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:06:40 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 25 18:06:40 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 25 18:06:40 rivoplus volumio[2181]: info: Discovery: Getting this device information Apr 25 18:06:40 rivoplus volumio[2181]: info: CoreCommandRouter::volumioGetState Apr 25 18:06:40 rivoplus volumio[2181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 25 18:06:48 rivoplus volumio[2181]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 18:06:48 rivoplus volumio[2181]: Error: connect ETIMEDOUT 151.101.134.79:80 Apr 25 18:06:48 rivoplus volumio[2181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 25 18:06:48 rivoplus volumio[2181]: errno: -110, Apr 25 18:06:48 rivoplus volumio[2181]: code: 'ETIMEDOUT', Apr 25 18:06:48 rivoplus volumio[2181]: syscall: 'connect', Apr 25 18:06:48 rivoplus volumio[2181]: address: '151.101.134.79', Apr 25 18:06:48 rivoplus volumio[2181]: port: 80 Apr 25 18:06:48 rivoplus volumio[2181]: } Apr 25 18:06:48 rivoplus volumio[2181]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 18:06:49 rivoplus sudo[8503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-25 18:05 Apr 25 18:06:49 rivoplus sudo[8503]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:42:11 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="4f5519a96ae5ba7dcfda362cc37343d4"