-- Logs begin at Thu 2025-04-17 21:29:09 EEST, end at Tue 2025-04-22 20:48:51 EEST. -- Apr 22 20:47:00 rivoplus volumio[3087]: info: explodeTIDALUri took 13758 milliseconds Apr 22 20:47:00 rivoplus volumio[3087]: STREAMING PROXY: Response: 200, length: 28596599 Apr 22 20:47:01 rivoplus volumio[3087]: info: explodeTIDALUri took 14740 milliseconds Apr 22 20:47:01 rivoplus volumio[3087]: info: explodeTIDALUri took 14686 milliseconds Apr 22 20:47:01 rivoplus volumio[3087]: info: explodeTIDALUri took 14692 milliseconds Apr 22 20:47:01 rivoplus volumio[3087]: info: explodeTIDALUri took 14726 milliseconds Apr 22 20:47:02 rivoplus volumio[3087]: info: explodeTIDALUri took 15749 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: error: Failed request for metavolumio API Apr 22 20:47:04 rivoplus volumio[3087]: error: Failed request for metavolumio API Apr 22 20:47:04 rivoplus volumio[3087]: error: Failed request for metavolumio API Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18288 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18304 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: CoreCommandRouter::volumioGetState Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18340 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18354 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18388 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18437 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18460 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18485 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18516 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18539 milliseconds Apr 22 20:47:04 rivoplus volumio[3087]: info: explodeTIDALUri took 18566 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18640 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18645 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18671 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18677 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18744 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18742 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18803 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18798 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18838 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18867 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18927 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18955 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18980 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19016 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19027 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19050 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19052 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19057 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19124 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19170 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19175 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19238 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19223 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19235 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19236 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19234 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19300 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19351 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19357 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18475 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 19459 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18530 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18556 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18582 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18583 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18585 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: CoreCommandRouter::volumioGetState Apr 22 20:47:05 rivoplus volumio[3087]: info: explodeTIDALUri took 18621 milliseconds Apr 22 20:47:05 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushQueue Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::saveQueue Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::updateTrackBlock Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::getTrackBlock Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPlay Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::play index 70 Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::addQueueItems Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::addQueueItems Apr 22 20:47:06 rivoplus volumio[3087]: info: Preload queue cleared Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/173878817 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/173878817 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/1386045 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/1386045 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/1390763 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/1390763 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/89358107 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/89358107 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/56593768 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/56593768 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/140132662 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/140132662 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/318913648 Apr 22 20:47:06 rivoplus volumio[3087]: info: Exploding uri tidal://mymusic/tracks/az/318913648 in service tidal Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/41879886 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/41879886 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/12945138 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/12945138 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/23279196 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/23279196 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/119011962 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/119011962 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/79121418 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/79121418 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/77623826 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/77623826 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/22627590 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/22627590 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/396319569 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/396319569 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/77630455 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/77630455 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/12207852 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/12207852 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/77607399 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/77607399 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/250215635 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/250215635 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/18297993 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/18297993 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/18298016 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/18298016 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/65935260 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/65935260 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/1722484 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/1722484 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/55668619 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/55668619 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/10494760 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/10494760 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/3969937 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/3969937 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/24606357 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/24606357 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/192227538 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/192227538 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/62996854 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/62996854 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/161699377 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/161699377 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/349133503 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/349133503 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/20409614 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/20409614 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/71432651 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/71432651 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/65165849 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/65165849 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/32570100 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/32570100 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/32570101 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/32570101 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/32570102 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/32570102 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/32570103 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/32570103 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/32570104 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/32570104 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/32570105 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/32570105 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/32570106 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/32570106 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/39198420 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/39198420 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/79071549 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/79071549 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/24596618 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/24596618 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/2216221 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/2216221 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/362389698 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/362389698 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/315628372 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/315628372 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/86973919 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/86973919 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/99034253 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/99034253 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/156520626 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/156520626 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/134887239 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/134887239 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/156520631 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/156520631 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/410484899 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/410484899 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/166512523 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/166512523 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/362389697 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/362389697 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/65908098 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/65908098 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/93718982 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/93718982 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/171974981 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/171974981 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/93387653 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/93387653 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/4443419 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/4443419 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/134492680 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/134492680 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/96321465 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/96321465 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/95695543 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/95695543 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/169503526 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/169503526 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/113847229 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/113847229 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/263367910 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/263367910 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/356879431 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/356879431 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/346450334 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/346450334 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/60528297 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/60528297 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/12859786 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/12859786 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/160760014 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/160760014 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/160760015 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/160760015 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/160760016 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/160760016 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/160760017 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/160760017 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/161699379 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/161699379 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/110723674 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/110723674 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/273171616 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/273171616 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/77732771 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/77732771 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/160760021 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/160760021 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/78147291 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/78147291 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/60528294 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/60528294 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/89759284 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/89759284 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/79471828 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/79471828 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/376316611 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/376316611 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/89414465 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/89414465 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/285373412 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/285373412 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/160760013 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/160760013 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/187432226 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/187432226 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/73602667 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/73602667 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/75663866 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/75663866 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/1284029 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/1284029 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/94958617 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/94958617 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/3962252 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/3962252 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/68968216 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/68968216 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/200109344 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/200109344 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/479206 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/479206 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/160760019 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/160760019 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/90242977 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/90242977 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/81353422 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/81353422 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/280476840 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/280476840 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/89759289 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/89759289 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/4997271 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/4997271 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/147576867 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/147576867 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/12207365 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/12207365 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/187374697 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/187374697 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/154313911 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/154313911 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/77619200 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/77619200 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/77621835 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/77621835 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/86973918 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/86973918 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/3629694 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/3629694 Apr 22 20:47:06 rivoplus volumio[3087]: info: Adding Item to queue: tidal://mymusic/tracks/az/413590283 Apr 22 20:47:06 rivoplus volumio[3087]: info: Using cached record of: tidal://mymusic/tracks/az/413590283 Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::stop Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::play index undefined Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::startPlaybackTimer Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::volumioGetVisibleSources Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 22 20:47:06 rivoplus volumio[3087]: info: [1745344026080] ControllerTidal::clearAddPlayTrack Apr 22 20:47:06 rivoplus volumio[3087]: info: Getting stream with soundQuality LOSSLESS Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces state update: player Apr 22 20:47:06 rivoplus volumio[3087]: info: sendMpdCommand stop took 67 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: ControllerMpd::getState Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand status Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand clear Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces state update: player Apr 22 20:47:06 rivoplus volumio[3087]: info: ControllerMpd::getState Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand status Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces state update: player Apr 22 20:47:06 rivoplus volumio[3087]: info: ControllerMpd::getState Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand status Apr 22 20:47:06 rivoplus volumio[3087]: info: getStreamUrl took 18763 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic1ZWM5MDBiNjkzMmIwM2MzNjMxNjA1NWRlOWRiZWY2ZV82MS5tcDQ/0.flac?Expires=1745347615&Signature=Ahx8kE7MIIXxDokIEjptJdUVbOeYo5yrd0v1Cr8IwV1P2NIxK0yq73g~C4uZLb4AoHD76VWMzl6uwbAQZpnx4yLO9okqy0JiRR0KTSyVZjMte2VgZI6XjEo8Tvst5dpU8R6Z9Tv7GP8wOQqEYH8aWg1okIBWWkoktJb2SftlhT2TOGmmKB0RC8zd66Y2J6Ja-SHVY6hiGyO3Ed-QGh0erV7t5lHmV0cn1JPSfievfp9wRC0FxZtl6k3uHfVHqe4Aq~Nx1G3B2PnzHCccmtDDgzJt34i6PCIKalHmFqg-VM59gtkVmbeaFWgcNXsCHP5TgM2gTrvX4kA6XIxuNGxggQ__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces system playlist update Apr 22 20:47:06 rivoplus volumio[3087]: info: Ignoring MPD Status Update Apr 22 20:47:06 rivoplus volumio[3087]: info: sendMpdCommand status took 61 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: sendMpdCommand clear took 61 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: sendMpdCommand status took 59 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: sendMpdCommand status took 56 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::parseState Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/404433494&soundQuality=LOSSLESS" Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::parseState Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::parseState Apr 22 20:47:06 rivoplus volumio[3087]: info: ControllerMpd::pushState Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::servicePushState Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:06 rivoplus volumio[3087]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 22 20:47:06 rivoplus volumio[3087]: info: ControllerMpd::pushState Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::servicePushState Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:06 rivoplus volumio[3087]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 22 20:47:06 rivoplus volumio[3087]: info: ------------------------------ 80ms Apr 22 20:47:06 rivoplus volumio[3087]: info: ------------------------------ 79ms Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces system playlist update Apr 22 20:47:06 rivoplus volumio[3087]: info: Ignoring MPD Status Update Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces system playlist update Apr 22 20:47:06 rivoplus volumio[3087]: info: Ignoring MPD Status Update Apr 22 20:47:06 rivoplus volumio[3087]: error: updateQueue error: null Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces system playlist update Apr 22 20:47:06 rivoplus volumio[3087]: info: Ignoring MPD Status Update Apr 22 20:47:06 rivoplus volumio[3087]: info: ------------------------------ 34ms Apr 22 20:47:06 rivoplus volumio[3087]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/404433494&soundQuality=LOSSLESS" took 31 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: ------------------------------ 11ms Apr 22 20:47:06 rivoplus volumio[3087]: info: ------------------------------ 9ms Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::parseTrackInfo Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand play Apr 22 20:47:06 rivoplus volumio[3087]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 22 20:47:06 rivoplus volumio[3087]: info: ------------------------------ 111ms Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces system playlist update Apr 22 20:47:06 rivoplus volumio[3087]: info: Ignoring MPD Status Update Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces system playlist update Apr 22 20:47:06 rivoplus volumio[3087]: info: Ignoring MPD Status Update Apr 22 20:47:06 rivoplus volumio[3087]: STREAMING PROXY: Handling url /?data=tidal://song/404433494&soundQuality=LOSSLESS Apr 22 20:47:06 rivoplus volumio[3087]: info: explodeTIDALUri took 11155 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: explodeTIDALUri took 11195 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [318913648] not found"} Apr 22 20:47:06 rivoplus volumio[3087]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/318913648 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [318913648] not found"} Apr 22 20:47:06 rivoplus volumio[3087]: info: ------------------------------ 122ms Apr 22 20:47:06 rivoplus volumio[3087]: info: sendMpdCommand play took 117 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: ------------------------------ 114ms Apr 22 20:47:06 rivoplus volumio[3087]: info: ------------------------------ 112ms Apr 22 20:47:06 rivoplus volumio[3087]: info: explodeTIDALUri took 19963 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: explodeTIDALUri took 11162 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: explodeTIDALUri took 11174 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: explodeTIDALUri took 11172 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: Executing endpoint getStreamUrltidal Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 22 20:47:06 rivoplus volumio[3087]: info: explodeTIDALUri took 11192 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushQueue Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::saveQueue Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::updateTrackBlock Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::getTrackBlock Apr 22 20:47:06 rivoplus volumio[3087]: info: explodeTIDALUri took 19996 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: info: getStreamUrl took 10328 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiQwYjcwM2Y3NTRkNzUyNTJiMmI2MGFmMzNhYjhiNGE3Zi5tcDQ/0.flac?Expires=1745347626&Signature=gSVXHyr3uoaAe16g9zK0IcaxFKcjKVssrSYKw~cbsKWQJ-9dPd-I3GdKGfiq47ZkN19SxkKCHF9d9S9xBIuaxQ3yBbMX~lLE5Ak1~9CI4mvYNKNdgv2ShPny1gwFvQFLpJGl5d-Q540dVtoCdStYH3-1yXLcWSiVg6ZDaWK05iMsf1yajc1THTmPUhdkW09g3IhXIwkmDiElT1o~OE-OZHv7VfpeQM4xH4sap5AXr8i1ThdrgbgVtW7f7lj0gMzRbI9oZiTFAkXrF6AxbK-A9VvGpwI9gvspRJfPVB7rlkQD3Wr2XKV38wKKcTNkV08OisgciancOTHwuiv2Hge1Kw__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 22 20:47:06 rivoplus volumio[3087]: STREAMING PROXY: Response: 200, length: 28596599 Apr 22 20:47:06 rivoplus volumio[3087]: info: getStreamUrl took 10838 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiQwYjcwM2Y3NTRkNzUyNTJiMmI2MGFmMzNhYjhiNGE3Zi5tcDQ/0.flac?Expires=1745347626&Signature=gSVXHyr3uoaAe16g9zK0IcaxFKcjKVssrSYKw~cbsKWQJ-9dPd-I3GdKGfiq47ZkN19SxkKCHF9d9S9xBIuaxQ3yBbMX~lLE5Ak1~9CI4mvYNKNdgv2ShPny1gwFvQFLpJGl5d-Q540dVtoCdStYH3-1yXLcWSiVg6ZDaWK05iMsf1yajc1THTmPUhdkW09g3IhXIwkmDiElT1o~OE-OZHv7VfpeQM4xH4sap5AXr8i1ThdrgbgVtW7f7lj0gMzRbI9oZiTFAkXrF6AxbK-A9VvGpwI9gvspRJfPVB7rlkQD3Wr2XKV38wKKcTNkV08OisgciancOTHwuiv2Hge1Kw__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 22 20:47:06 rivoplus volumio[3087]: info: explodeTIDALUri took 638 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [318913648] not found"} Apr 22 20:47:06 rivoplus volumio[3087]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/318913648 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [318913648] not found"} Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushQueue Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::saveQueue Apr 22 20:47:06 rivoplus volumio[3087]: info: CoreStateMachine::updateTrackBlock Apr 22 20:47:06 rivoplus volumio[3087]: info: CorePlayQueue::getTrackBlock Apr 22 20:47:06 rivoplus volumio[3087]: info: getStreamUrl took 421 milliseconds Apr 22 20:47:06 rivoplus volumio[3087]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiQwYjcwM2Y3NTRkNzUyNTJiMmI2MGFmMzNhYjhiNGE3Zi5tcDQ/0.flac?Expires=1745347626&Signature=gSVXHyr3uoaAe16g9zK0IcaxFKcjKVssrSYKw~cbsKWQJ-9dPd-I3GdKGfiq47ZkN19SxkKCHF9d9S9xBIuaxQ3yBbMX~lLE5Ak1~9CI4mvYNKNdgv2ShPny1gwFvQFLpJGl5d-Q540dVtoCdStYH3-1yXLcWSiVg6ZDaWK05iMsf1yajc1THTmPUhdkW09g3IhXIwkmDiElT1o~OE-OZHv7VfpeQM4xH4sap5AXr8i1ThdrgbgVtW7f7lj0gMzRbI9oZiTFAkXrF6AxbK-A9VvGpwI9gvspRJfPVB7rlkQD3Wr2XKV38wKKcTNkV08OisgciancOTHwuiv2Hge1Kw__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 22 20:47:06 rivoplus volumio[3087]: STREAMING PROXY: Response: 200, length: 23243269 Apr 22 20:47:06 rivoplus volumio[3087]: STREAMING PROXY: Response: 200, length: 23243269 Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces state update: player Apr 22 20:47:06 rivoplus volumio[3087]: info: ControllerMpd::getState Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand status Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces state update: player Apr 22 20:47:06 rivoplus volumio[3087]: STREAMING PROXY: Response: 200, length: 23243269 Apr 22 20:47:06 rivoplus volumio[3087]: info: ControllerMpd::getState Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand status Apr 22 20:47:06 rivoplus volumio[3087]: info: Apr 22 20:47:06 rivoplus volumio[3087]: ---------------------------- MPD announces state update: player Apr 22 20:47:06 rivoplus volumio[3087]: info: ControllerMpd::getState Apr 22 20:47:06 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand status Apr 22 20:47:07 rivoplus volumio[3087]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioGetState Apr 22 20:47:07 rivoplus volumio[3087]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand status took 146 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseState Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 20:47:07 rivoplus volumio[3087]: info: Apr 22 20:47:07 rivoplus volumio[3087]: ---------------------------- MPD announces state update: player Apr 22 20:47:07 rivoplus volumio[3087]: info: ControllerMpd::getState Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand status Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand status took 149 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand status took 148 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseState Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseState Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseTrackInfo Apr 22 20:47:07 rivoplus volumio[3087]: info: ControllerMpd::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::servicePushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:07 rivoplus volumio[3087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"404433494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/404433494&soundQuality=LOSSLESS","trackType":"tidal"} Apr 22 20:47:07 rivoplus volumio[3087]: verbose: CURRENT POSITION 70 Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState stateService play Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState currentStatus stop Apr 22 20:47:07 rivoplus volumio[3087]: info: ------------------------------ 160ms Apr 22 20:47:07 rivoplus volumio[3087]: info: Apr 22 20:47:07 rivoplus volumio[3087]: ---------------------------- MPD announces state update: player Apr 22 20:47:07 rivoplus volumio[3087]: info: ControllerMpd::getState Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand status Apr 22 20:47:07 rivoplus volumio[3087]: info: Apr 22 20:47:07 rivoplus volumio[3087]: ---------------------------- MPD announces state update: player Apr 22 20:47:07 rivoplus volumio[3087]: info: ControllerMpd::getState Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand status Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand status took 13 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand status took 5 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand status took 4 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseState Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseTrackInfo Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseTrackInfo Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseState Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseState Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 20:47:07 rivoplus volumio[3087]: info: ControllerMpd::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::servicePushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:07 rivoplus volumio[3087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"404433494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/404433494&soundQuality=LOSSLESS","trackType":"tidal"} Apr 22 20:47:07 rivoplus volumio[3087]: verbose: CURRENT POSITION 70 Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState stateService play Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState currentStatus play Apr 22 20:47:07 rivoplus volumio[3087]: info: Received an update from plugin. extracting info from payload Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: ControllerMpd::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::servicePushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:07 rivoplus volumio[3087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"597 Kbps","isStreaming":false,"title":"404433494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/404433494&soundQuality=LOSSLESS","trackType":"tidal"} Apr 22 20:47:07 rivoplus volumio[3087]: verbose: CURRENT POSITION 70 Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState stateService play Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState currentStatus play Apr 22 20:47:07 rivoplus volumio[3087]: info: Received an update from plugin. extracting info from payload Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: ------------------------------ 199ms Apr 22 20:47:07 rivoplus volumio[3087]: info: ------------------------------ 198ms Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand playlistinfo took 38 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseTrackInfo Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseTrackInfo Apr 22 20:47:07 rivoplus volumio[3087]: verbose: ControllerMpd::parseTrackInfo Apr 22 20:47:07 rivoplus volumio[3087]: info: ControllerMpd::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::servicePushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:07 rivoplus volumio[3087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"630 Kbps","isStreaming":false,"title":"404433494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/404433494&soundQuality=LOSSLESS","trackType":"tidal"} Apr 22 20:47:07 rivoplus volumio[3087]: verbose: CURRENT POSITION 70 Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState stateService play Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState currentStatus play Apr 22 20:47:07 rivoplus volumio[3087]: info: Received an update from plugin. extracting info from payload Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: ControllerMpd::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::servicePushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:07 rivoplus volumio[3087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"630 Kbps","isStreaming":false,"title":"404433494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/404433494&soundQuality=LOSSLESS","trackType":"tidal"} Apr 22 20:47:07 rivoplus volumio[3087]: verbose: CURRENT POSITION 70 Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState stateService play Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState currentStatus play Apr 22 20:47:07 rivoplus volumio[3087]: info: Received an update from plugin. extracting info from payload Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: ControllerMpd::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::servicePushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:07 rivoplus volumio[3087]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"630 Kbps","isStreaming":false,"title":"404433494&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/404433494&soundQuality=LOSSLESS","trackType":"tidal"} Apr 22 20:47:07 rivoplus volumio[3087]: verbose: CURRENT POSITION 70 Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState stateService play Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::syncState currentStatus play Apr 22 20:47:07 rivoplus volumio[3087]: info: Received an update from plugin. extracting info from payload Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:07 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:07 rivoplus volumio[3087]: info: ------------------------------ 115ms Apr 22 20:47:07 rivoplus volumio[3087]: info: ------------------------------ 107ms Apr 22 20:47:07 rivoplus volumio[3087]: info: ------------------------------ 106ms Apr 22 20:47:07 rivoplus volumio[3087]: info: MCU Signalled Playback Inactive Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:07 rivoplus volumio[3087]: info: MCU Signalled Playback Active Apr 22 20:47:08 rivoplus volumio[3087]: STREAMING PROXY: Client dropped request, destroying Apr 22 20:47:08 rivoplus volumio[3087]: info: Executing endpoint metavolumio Apr 22 20:47:08 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 20:47:08 rivoplus volumio[3087]: info: Executing endpoint metavolumio Apr 22 20:47:08 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 20:47:08 rivoplus volumio[3087]: info: Executing endpoint metavolumio Apr 22 20:47:08 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 20:47:12 rivoplus volumio[3087]: info: VolumeController::SetAlsaVolume42 Apr 22 20:47:12 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:12 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:47:12 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:12 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:12 rivoplus volumio[3087]: info: VolumeController::SetAlsaVolume68 Apr 22 20:47:12 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:47:12 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:47:12 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:47:12 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:47:50 rivoplus volumio[3087]: info: CorePlayQueue::getTrack 70 Apr 22 20:47:50 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: tidal , goto Apr 22 20:47:50 rivoplus volumio[3087]: info: explodeTIDALUri took 258 milliseconds Apr 22 20:47:51 rivoplus volumio[3087]: info: browseTIDALUri took 867 milliseconds Apr 22 20:47:51 rivoplus volumio[3087]: info: Executing endpoint getSimilarAlbums Apr 22 20:47:51 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 22 20:47:51 rivoplus volumio[3087]: info: Executing endpoint getSimilarAlbums Apr 22 20:47:51 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 22 20:47:51 rivoplus volumio[3087]: info: Executing endpoint metavolumio Apr 22 20:47:51 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 20:47:51 rivoplus volumio[3087]: info: Executing endpoint metavolumio Apr 22 20:47:51 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 20:47:51 rivoplus volumio[3087]: info: Executing endpoint metavolumio Apr 22 20:47:51 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 20:47:51 rivoplus volumio[3087]: info: Executing endpoint metavolumio Apr 22 20:47:51 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 20:48:00 rivoplus volumio[3087]: info: CoreCommandRouter::volumioplayNextItems Apr 22 20:48:00 rivoplus volumio[3087]: info: CoreStateMachine::playNextItems Apr 22 20:48:00 rivoplus volumio[3087]: info: CorePlayQueue::addPlayNextItems Apr 22 20:48:00 rivoplus volumio[3087]: info: Preload queue cleared Apr 22 20:48:00 rivoplus volumio[3087]: info: Adding Item to play next: tidal://album/404433478 Apr 22 20:48:00 rivoplus volumio[3087]: info: Exploding uri tidal://album/404433478 in service tidal Apr 22 20:48:01 rivoplus volumio[3087]: info: explodeTIDALUri took 357 milliseconds Apr 22 20:48:01 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushQueue Apr 22 20:48:01 rivoplus volumio[3087]: info: CorePlayQueue::saveQueue Apr 22 20:48:01 rivoplus volumio[3087]: info: CoreStateMachine::updateTrackBlock Apr 22 20:48:01 rivoplus volumio[3087]: info: CorePlayQueue::getTrackBlock Apr 22 20:48:08 rivoplus volumio[3087]: info: VolumeController::SetAlsaVolume71 Apr 22 20:48:08 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:48:08 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:48:08 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:48:08 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:48:09 rivoplus volumio[3087]: info: VolumeController::SetAlsaVolume79 Apr 22 20:48:09 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:48:09 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:48:09 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:48:09 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:48:09 rivoplus ntpd[3049]: 89.46.74.148 local addr 192.168.1.110 -> Apr 22 20:48:12 rivoplus volumio[3087]: info: VolumeController::SetAlsaVolume- Apr 22 20:48:12 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:48:12 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:48:12 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:48:12 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:48:13 rivoplus volumio[3087]: info: VolumeController::SetAlsaVolume- Apr 22 20:48:13 rivoplus volumio[3087]: info: CoreStateMachine::pushState Apr 22 20:48:13 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 20:48:13 rivoplus volumio[3087]: info: CoreCommandRouter::volumioPushState Apr 22 20:48:13 rivoplus volumio[3087]: info: Signalling Playback active due to playback status change Apr 22 20:48:19 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 22 20:48:19 rivoplus volumio[3087]: info: Preload queue cleared Apr 22 20:48:20 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 22 20:48:20 rivoplus volumio[3087]: info: Preload queue cleared Apr 22 20:48:25 rivoplus volumio[3087]: Searching plugin music_service/upnp_browser Apr 22 20:48:25 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 22 20:48:25 rivoplus volumio[3087]: info: All search sources collected, pushing search results Apr 22 20:48:27 rivoplus volumio[3087]: Searching plugin music_service/upnp_browser Apr 22 20:48:27 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 22 20:48:27 rivoplus volumio[3087]: info: All search sources collected, pushing search results Apr 22 20:48:38 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 22 20:48:38 rivoplus volumio[3087]: info: Preload queue cleared Apr 22 20:48:47 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 22 20:48:47 rivoplus volumio[3087]: info: Preload queue cleared Apr 22 20:48:50 rivoplus volumio[3087]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 22 20:48:50 rivoplus volumio[3087]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 20:48:50 rivoplus volumio[3087]: TypeError: Cannot read property 'length' of undefined Apr 22 20:48:50 rivoplus volumio[3087]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 22 20:48:50 rivoplus volumio[3087]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 22 20:48:50 rivoplus volumio[3087]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 22 20:48:50 rivoplus volumio[3087]: at Parser.emit (events.js:400:28) Apr 22 20:48:50 rivoplus volumio[3087]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 22 20:48:50 rivoplus volumio[3087]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 22 20:48:50 rivoplus volumio[3087]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 22 20:48:50 rivoplus volumio[3087]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 22 20:48:50 rivoplus volumio[3087]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 22 20:48:50 rivoplus volumio[3087]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 22 20:48:50 rivoplus volumio[3087]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 22 20:48:50 rivoplus volumio[3087]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 22 20:48:50 rivoplus volumio[3087]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 22 20:48:50 rivoplus volumio[3087]: at IncomingMessage.emit (events.js:412:35) Apr 22 20:48:50 rivoplus volumio[3087]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 22 20:48:50 rivoplus volumio[3087]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 22 20:48:50 rivoplus volumio[3087]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 20:48:51 rivoplus sudo[9747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 20:47 Apr 22 20:48:51 rivoplus sudo[9747]: 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"