-- Logs begin at Thu 2019-02-14 12:11:58 SAST, end at Fri 2025-01-10 23:01:00 SAST. -- Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Preload queue cleared Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::ClearQueue Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::stop Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::stPlaybackTimer Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::updateTrackBlock Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrackBlock Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::pushState Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 6 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushState Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::serviceStop Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 6 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::serviceStop Jan 10 23:00:42 pi2aes1 volumio[1044]: info: [1736542842493] ControllerTidal::stop Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ControllerMpd::stop Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::clearPlayQueue Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::saveQueue Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushQueue Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::addQueueItems Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::addQueueItems Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Preload queue cleared Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/372020061 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/372020061 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/317391314 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/317391314 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/319404493 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/319404493 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/326447753 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/326447753 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/327557114 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/327557114 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/315911777 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/315911777 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/324625855 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/324625855 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/325423328 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/325423328 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/324635172 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/324635172 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/321903354 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/321903354 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/321850744 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/321850744 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/312008844 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/312008844 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/320528989 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/320528989 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/312078456 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/312078456 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/311623545 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/311623545 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/320721217 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/320721217 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/311984754 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/311984754 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushQueue Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::saveQueue Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::updateTrackBlock Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrackBlock Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPlay Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::play index 16 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::addQueueItems Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::addQueueItems Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Preload queue cleared Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/319063540 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/319063540 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/319064867 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/319064867 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/308228352 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/308228352 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/311633306 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/311633306 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/317691506 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/317691506 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/317694028 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/317694028 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/311984752 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/311984752 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/316409557 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/316409557 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/307105896 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/307105896 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/313953959 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/313953959 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/304324254 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/304324254 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/338579524 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/338579524 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/312767761 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/312767761 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/313098570 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/313098570 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/299117739 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/299117739 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/313098600 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/313098600 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/303140113 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/303140113 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/301727114 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/301727114 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/304636497 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/304636497 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/307599849 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/307599849 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/302338951 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/302338951 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/307597816 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/307597816 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/306141305 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/306141305 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/301722502 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/301722502 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/306129306 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/306129306 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/302812357 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/302812357 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/302518379 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/302518379 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/290442894 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/290442894 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/300187236 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/300187236 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/300489873 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/300489873 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/291439234 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/291439234 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/296903498 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/296903498 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Adding Item to queue: tidal://song/284200507 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Using cached record of: tidal://song/284200507 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::stop Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushQueue Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::saveQueue Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::play index undefined Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::updateTrackBlock Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrackBlock Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 16 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 16 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: [1736542842520] ControllerTidal::clearAddPlayTrack Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Getting stream with soundQuality LOSSLESS Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Jan 10 23:00:42 pi2aes1 volumio[1044]: ---------------------------- MPD announces state update: player Jan 10 23:00:42 pi2aes1 volumio[1044]: info: sendMpdCommand stop took 37 milliseconds Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ControllerMpd::getState Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jan 10 23:00:42 pi2aes1 volumio[1044]: info: sendMpdCommand stop took 9 milliseconds Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Jan 10 23:00:42 pi2aes1 volumio[1044]: ---------------------------- MPD announces state update: player Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ControllerMpd::getState Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Jan 10 23:00:42 pi2aes1 volumio[1044]: ---------------------------- MPD announces system playlist update Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Ignoring MPD Status Update Jan 10 23:00:42 pi2aes1 volumio[1044]: info: sendMpdCommand status took 3 milliseconds Jan 10 23:00:42 pi2aes1 volumio[1044]: info: sendMpdCommand clear took 2 milliseconds Jan 10 23:00:42 pi2aes1 volumio[1044]: info: sendMpdCommand status took 1 milliseconds Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseState Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/311984754&soundQuality=LOSSLESS" Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseState Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ControllerMpd::pushState Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::servicePushState Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::pushState Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 16 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushState Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 16 Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ------------------------------ 7ms Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Jan 10 23:00:42 pi2aes1 volumio[1044]: ---------------------------- MPD announces system playlist update Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Ignoring MPD Status Update Jan 10 23:00:42 pi2aes1 volumio[1044]: error: updateQueue error: null Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Jan 10 23:00:42 pi2aes1 volumio[1044]: ---------------------------- MPD announces system playlist update Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Ignoring MPD Status Update Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ------------------------------ 7ms Jan 10 23:00:42 pi2aes1 volumio[1044]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 10 23:00:42 pi2aes1 volumio[1044]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/311984754&soundQuality=LOSSLESS" took 6 milliseconds Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 10 23:00:42 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jan 10 23:00:42 pi2aes1 volumio[1044]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ------------------------------ 10ms Jan 10 23:00:42 pi2aes1 volumio[1044]: STREAMING PROXY: Handling url /?data=tidal://song/311984754&soundQuality=LOSSLESS Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ------------------------------ 8ms Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ------------------------------ 7ms Jan 10 23:00:42 pi2aes1 volumio[1044]: info: sendMpdCommand play took 6 milliseconds Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Jan 10 23:00:42 pi2aes1 volumio[1044]: ---------------------------- MPD announces system playlist update Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Ignoring MPD Status Update Jan 10 23:00:42 pi2aes1 volumio[1044]: info: ------------------------------ 1ms Jan 10 23:00:42 pi2aes1 volumio[1044]: info: Executing endpoint getStreamUrltidal Jan 10 23:00:42 pi2aes1 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 10 23:00:43 pi2aes1 volumio[1044]: info: getStreamUrl took 508 milliseconds Jan 10 23:00:43 pi2aes1 volumio[1044]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidkOTFiZDk2MzRkY2NkOWI5NDdhYTYyNWIzZjE0ZjRiYV82MS5tcDQ/0.flac?Expires=1736546442&Signature=CZTP7FNeteCtrmPP8jfpzVFv63A0z~PTpOAYK0cIUcq6HN0MrqqOVpUk85wxqFWQKTk-suUPkcnNLS4V~uFpMSY1jh-lzD3U-Pcwvz-8kWaln-6f59a5ZENrKmoYiXw8flf3TzS5wrqM0Dl25-JNN6giblFeocEiWzP-YxYq-Fv2ZAcGO4g42wwzVD9bS1Klg6GwXzTi-VMzQt0MPCBsKcGQbF2Opzf7EeaE2wn2m~RUgDIwlpDzrWWJ9FWDbPUuC7TQc44xfFqS-bxlmzqBmRT0xaZd2k4UF1V4w7GDw-gYyQ2eSEVaw-NhB12-mKb3BX-So5YOe3BKhdkEY8iayA__&Key-Pair-Id=K14LZCZ9QUI4JL Jan 10 23:00:43 pi2aes1 volumio[1044]: STREAMING PROXY: Response: 200, length: 14411980 Jan 10 23:00:43 pi2aes1 volumio[1044]: info: Jan 10 23:00:43 pi2aes1 volumio[1044]: ---------------------------- MPD announces state update: player Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ControllerMpd::getState Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jan 10 23:00:43 pi2aes1 volumio[1044]: info: Jan 10 23:00:43 pi2aes1 volumio[1044]: ---------------------------- MPD announces state update: player Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ControllerMpd::getState Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jan 10 23:00:43 pi2aes1 volumio[1044]: info: Jan 10 23:00:43 pi2aes1 volumio[1044]: ---------------------------- MPD announces state update: player Jan 10 23:00:43 pi2aes1 volumio[1044]: info: sendMpdCommand status took 1 milliseconds Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ControllerMpd::getState Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseState Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 23:00:43 pi2aes1 volumio[1044]: info: Jan 10 23:00:43 pi2aes1 volumio[1044]: ---------------------------- MPD announces state update: player Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ControllerMpd::getState Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jan 10 23:00:43 pi2aes1 volumio[1044]: info: sendMpdCommand status took 5 milliseconds Jan 10 23:00:43 pi2aes1 volumio[1044]: info: sendMpdCommand status took 4 milliseconds Jan 10 23:00:43 pi2aes1 volumio[1044]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseState Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseState Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ControllerMpd::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::servicePushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 16 Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"311984754&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/311984754&soundQuality=LOSSLESS","trackType":"tidal"} Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: CURRENT POSITION 16 Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::syncState stateService play Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ------------------------------ 11ms Jan 10 23:00:43 pi2aes1 volumio[1044]: info: sendMpdCommand status took 8 milliseconds Jan 10 23:00:43 pi2aes1 volumio[1044]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 10 23:00:43 pi2aes1 volumio[1044]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseState Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ControllerMpd::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::servicePushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 16 Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"311984754&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/311984754&soundQuality=LOSSLESS","trackType":"tidal"} Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: CURRENT POSITION 16 Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::syncState stateService play Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jan 10 23:00:43 pi2aes1 volumio[1044]: info: Received an update from plugin. extracting info from payload Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ControllerMpd::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::servicePushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 16 Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"311984754&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/311984754&soundQuality=LOSSLESS","trackType":"tidal"} Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: CURRENT POSITION 16 Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::syncState stateService play Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jan 10 23:00:43 pi2aes1 volumio[1044]: info: Received an update from plugin. extracting info from payload Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ------------------------------ 30ms Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ------------------------------ 30ms Jan 10 23:00:43 pi2aes1 volumio[1044]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ControllerMpd::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::servicePushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CorePlayQueue::getTrack 16 Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"311984754&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/311984754&soundQuality=LOSSLESS","trackType":"tidal"} Jan 10 23:00:43 pi2aes1 volumio[1044]: verbose: CURRENT POSITION 16 Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::syncState stateService play Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::syncState currentStatus play Jan 10 23:00:43 pi2aes1 volumio[1044]: info: Received an update from plugin. extracting info from payload Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreStateMachine::pushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: CoreCommandRouter::volumioPushState Jan 10 23:00:43 pi2aes1 volumio[1044]: info: ------------------------------ 36ms Jan 10 23:00:44 pi2aes1 volumio[1044]: STREAMING PROXY: Client dropped request, destroying Jan 10 23:00:54 pi2aes1 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 10 23:00:54 pi2aes1 volumio[1044]: info: Preload queue cleared Jan 10 23:00:55 pi2aes1 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 10 23:00:55 pi2aes1 volumio[1044]: error: Error browsing0:Error: Did not get expected response from server:{"Envelope":{"$":{"xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/","s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"501"}],"errorDescription":[{"_":"Action Failed"}]}]}]}]}]}} Jan 10 23:00:55 pi2aes1 volumio[1044]: error: Failed to execute browseSource: Jan 10 23:00:56 pi2aes1 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 10 23:00:56 pi2aes1 volumio[1044]: error: Error browsing0:Error: Did not get expected response from server:{"Envelope":{"$":{"xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/","s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"501"}],"errorDescription":[{"_":"Action Failed"}]}]}]}]}]}} Jan 10 23:00:56 pi2aes1 volumio[1044]: error: Failed to execute browseSource: Jan 10 23:00:58 pi2aes1 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 10 23:00:58 pi2aes1 volumio[1044]: info: Preload queue cleared Jan 10 23:01:00 pi2aes1 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 10 23:01:00 pi2aes1 volumio[1044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 23:01:00 pi2aes1 volumio[1044]: TypeError: Cannot read property 'length' of undefined Jan 10 23:01:00 pi2aes1 volumio[1044]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 10 23:01:00 pi2aes1 volumio[1044]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 10 23:01:00 pi2aes1 volumio[1044]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 10 23:01:00 pi2aes1 volumio[1044]: at Parser.emit (events.js:315:20) Jan 10 23:01:00 pi2aes1 volumio[1044]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 10 23:01:00 pi2aes1 volumio[1044]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 10 23:01:00 pi2aes1 volumio[1044]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 10 23:01:00 pi2aes1 volumio[1044]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 10 23:01:00 pi2aes1 volumio[1044]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 10 23:01:00 pi2aes1 volumio[1044]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 10 23:01:00 pi2aes1 volumio[1044]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 10 23:01:00 pi2aes1 volumio[1044]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 10 23:01:00 pi2aes1 volumio[1044]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 10 23:01:00 pi2aes1 volumio[1044]: at IncomingMessage.emit (events.js:327:22) Jan 10 23:01:00 pi2aes1 volumio[1044]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 10 23:01:00 pi2aes1 volumio[1044]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 10 23:01:00 pi2aes1 volumio[1044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 23:01:00 pi2aes1 sudo[8388]: volumio : unable to resolve host pi2aes1 Jan 10 23:01:00 pi2aes1 sudo[8388]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 10 23:01:00 pi2aes1 sudo[8388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-10 23:00 Jan 10 23:01:00 pi2aes1 sudo[8388]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"