-- Logs begin at Sun 2024-08-11 17:06:39 CEST, end at Wed 2024-10-23 17:22:25 CEST. -- Oct 23 17:21:05 volumio volumio[826]: info: Preload queue cleared Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::ClearQueue Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::stPlaybackTimer Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::updateTrackBlock Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrackBlock Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 21 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::serviceStop Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 21 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::serviceStop Oct 23 17:21:05 volumio volumio[826]: info: [1729696865816] ControllerUPNPBrowser::stop Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand stop Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::clearPlayQueue Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::addQueueItems Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::addQueueItems Oct 23 17:21:05 volumio volumio[826]: info: Preload queue cleared Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42310 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42310 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42314 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42314 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42313 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42313 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@578662 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@578662 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623607 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623607 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623595 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623595 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623596 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623596 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623606 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623606 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623599 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623599 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623608 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623608 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623610 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623610 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623609 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623609 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623613 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623613 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623601 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623601 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623600 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623600 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623612 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623612 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623597 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623597 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623594 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623594 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623598 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623598 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623611 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623611 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623626 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623626 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623628 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623628 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623632 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623632 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623619 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623619 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623624 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623624 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623627 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623627 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623615 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623615 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623616 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623616 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623614 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623614 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623625 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623625 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623629 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623629 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623617 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623617 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623623 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623623 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623631 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623631 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623622 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623622 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623620 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623620 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623578 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623578 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623566 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623566 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623567 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623567 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623591 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623591 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623592 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623592 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623569 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623569 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623570 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623570 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623593 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623593 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623584 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623584 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623572 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623572 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623585 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623585 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623573 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623573 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623582 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623582 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623574 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623574 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623590 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623590 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623581 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623581 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623576 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623576 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623554 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623554 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623580 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623580 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623555 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623555 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623579 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623579 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623556 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623556 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623557 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623557 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623577 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623577 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623558 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623558 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623583 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623583 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623559 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623559 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623588 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623588 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623587 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623587 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623561 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623561 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623562 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623562 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623589 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623589 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623563 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623563 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623586 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623586 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623564 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623564 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623565 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623565 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623658 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623658 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623675 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623675 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623662 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623662 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623655 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623655 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623646 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623646 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623657 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623657 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623668 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623668 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623647 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623647 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623635 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623635 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623652 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623652 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623653 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623653 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623654 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623654 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623633 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623633 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623671 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623671 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623636 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623636 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623656 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623656 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623677 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623677 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623670 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623670 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623650 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623650 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623645 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623645 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623637 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623637 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623672 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623672 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623642 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623642 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623644 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623644 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623669 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623669 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623674 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623674 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623634 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623634 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623648 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623648 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623663 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623663 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623640 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623640 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623651 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623651 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623643 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623643 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623639 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623639 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623659 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623659 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623667 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623667 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623676 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623676 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623638 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623638 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623641 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623641 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623666 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623666 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623660 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623660 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623673 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623673 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623649 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623649 Oct 23 17:21:05 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623665 Oct 23 17:21:05 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623665 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::updateTrackBlock Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrackBlock Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPlay Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::play index 59 Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::play index undefined Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 59 Oct 23 17:21:05 volumio volumio[826]: info: Oct 23 17:21:05 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:05 volumio volumio[826]: info: sendMpdCommand stop took 43 milliseconds Oct 23 17:21:05 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:05 volumio volumio[826]: info: Oct 23 17:21:05 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:05 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:05 volumio volumio[826]: info: Oct 23 17:21:05 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:05 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:05 volumio volumio[826]: info: sendMpdCommand status took 25 milliseconds Oct 23 17:21:05 volumio volumio[826]: info: sendMpdCommand status took 24 milliseconds Oct 23 17:21:05 volumio volumio[826]: info: sendMpdCommand status took 24 milliseconds Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:05 volumio volumio[826]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 23 17:21:05 volumio volumio[826]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 23 17:21:05 volumio volumio[826]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:05 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:05 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623740.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623740.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:05 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::syncState stateService stop Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus stop Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: No code Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623740.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623740.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:05 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::syncState stateService stop Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus stop Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: No code Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623740.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623740.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:05 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::syncState stateService stop Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus stop Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: No code Oct 23 17:21:05 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:05 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:05 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:05 volumio volumio[826]: info: ------------------------------ 83ms Oct 23 17:21:05 volumio volumio[826]: info: ------------------------------ 83ms Oct 23 17:21:05 volumio volumio[826]: info: ------------------------------ 82ms Oct 23 17:21:05 volumio volumio[826]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 23 17:21:06 volumio volumio[826]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 23 17:21:06 volumio volumio[826]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 23 17:21:10 volumio volumio[826]: info: Preload queue cleared Oct 23 17:21:10 volumio volumio[826]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::ClearQueue Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:10 volumio volumio[826]: info: CorePlayQueue::clearPlayQueue Oct 23 17:21:10 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:21:10 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::addQueueItems Oct 23 17:21:10 volumio volumio[826]: info: CorePlayQueue::addQueueItems Oct 23 17:21:10 volumio volumio[826]: info: Preload queue cleared Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42310 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42310 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42314 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42314 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42313 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42313 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@578662 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@578662 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623607 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623607 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623595 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623595 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623596 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623596 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623606 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623606 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623599 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623599 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623608 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623608 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623610 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623610 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623609 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623609 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623613 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623613 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623601 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623601 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623600 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623600 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623612 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623612 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623597 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623597 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623594 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623594 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623598 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623598 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623611 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623611 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623626 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623626 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623628 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623628 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623632 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623632 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623619 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623619 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623624 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623624 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623627 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623627 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623615 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623615 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623616 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623616 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623614 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623614 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623625 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623625 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623629 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623629 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623617 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623617 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623623 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623623 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623631 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623631 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623622 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623622 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623620 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623620 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623578 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623578 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623566 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623566 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623567 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623567 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623591 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623591 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623592 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623592 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623569 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623569 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623570 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623570 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623593 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623593 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623584 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623584 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623572 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623572 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623585 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623585 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623573 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623573 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623582 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623582 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623574 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623574 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623590 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623590 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623581 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623581 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623576 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623576 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623554 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623554 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623580 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623580 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623555 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623555 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623579 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623579 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623556 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623556 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623557 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623557 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623577 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623577 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623558 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623558 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623583 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623583 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623559 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623559 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623588 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623588 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623587 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623587 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623561 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623561 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623562 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623562 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623589 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623589 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623563 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623563 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623586 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623586 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623564 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623564 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623565 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623565 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623658 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623658 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623675 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623675 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623662 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623662 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623655 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623655 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623646 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623646 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623657 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623657 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623668 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623668 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623647 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623647 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623635 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623635 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623652 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623652 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623653 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623653 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623654 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623654 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623633 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623633 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623671 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623671 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623636 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623636 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623656 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623656 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623677 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623677 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623670 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623670 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623650 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623650 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623645 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623645 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623637 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623637 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623672 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623672 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623642 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623642 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623644 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623644 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623669 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623669 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623674 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623674 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623634 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623634 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623648 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623648 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623663 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623663 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623640 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623640 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623651 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623651 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623643 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623643 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623639 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623639 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623659 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623659 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623667 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623667 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623676 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623676 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623638 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623638 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623641 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623641 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623666 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623666 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623660 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623660 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623673 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623673 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623649 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623649 Oct 23 17:21:10 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623665 Oct 23 17:21:10 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623665 Oct 23 17:21:10 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:21:10 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::updateTrackBlock Oct 23 17:21:10 volumio volumio[826]: info: CorePlayQueue::getTrackBlock Oct 23 17:21:10 volumio volumio[826]: info: CoreCommandRouter::volumioPlay Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::play index 59 Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::play index undefined Oct 23 17:21:10 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:10 volumio volumio[826]: info: CorePlayQueue::getTrack 59 Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPlay Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::play index undefined Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:14 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::startPlaybackTimer Oct 23 17:21:14 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:14 volumio volumio[826]: info: [1729696874012] ControllerUPNPBrowser::clearAddPlayTrack Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand stop Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand stop took 1 milliseconds Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand clear Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 23 17:21:14 volumio volumio[826]: info: Ignoring MPD Status Update Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand clear took 1 milliseconds Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true" Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 23 17:21:14 volumio volumio[826]: info: Ignoring MPD Status Update Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 23 17:21:14 volumio volumio[826]: info: Ignoring MPD Status Update Oct 23 17:21:14 volumio volumio[826]: error: updateQueue error: null Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 3ms Oct 23 17:21:14 volumio volumio[826]: error: updateQueue error: null Oct 23 17:21:14 volumio volumio[826]: error: updateQueue error: null Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true" Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 177ms Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 178ms Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 23 17:21:14 volumio volumio[826]: info: Ignoring MPD Status Update Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand add "http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true" took 5 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand play Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 23 17:21:14 volumio volumio[826]: info: Ignoring MPD Status Update Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 23 17:21:14 volumio volumio[826]: info: Ignoring MPD Status Update Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 3ms Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand play took 4 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 3ms Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 2ms Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand status took 24 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand status took 32 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand status took 33 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand status took 8 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:14 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:14 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:14 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus stop Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 45ms Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:14 volumio volumio[826]: info: Oct 23 17:21:14 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand status took 4 milliseconds Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:14 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:14 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:14 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 23 17:21:14 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:14 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:14 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:14 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 23 17:21:14 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:14 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:14 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:14 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 23 17:21:14 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 89ms Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 89ms Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 71ms Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand status took 44 milliseconds Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:14 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:14 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:14 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 23 17:21:14 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 58ms Oct 23 17:21:14 volumio volumio[826]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 23 17:21:14 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:14 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:14 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:14 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:14 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 23 17:21:14 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:14 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:14 volumio volumio[826]: info: ------------------------------ 78ms Oct 23 17:21:17 volumio volumio[826]: info: CoreCommandRouter::volumioPause Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::pause Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::stPlaybackTimer Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::servicePause Oct 23 17:21:17 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:17 volumio volumio[826]: info: CoreCommandRouter::servicePause Oct 23 17:21:17 volumio volumio[826]: info: [1729696877064] ControllerUPNPBrowser::pause Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand pause Oct 23 17:21:17 volumio volumio[826]: info: Oct 23 17:21:17 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:17 volumio volumio[826]: info: sendMpdCommand pause took 1 milliseconds Oct 23 17:21:17 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:17 volumio volumio[826]: info: Oct 23 17:21:17 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:17 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:17 volumio volumio[826]: info: Oct 23 17:21:17 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:17 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:17 volumio volumio[826]: info: sendMpdCommand status took 2 milliseconds Oct 23 17:21:17 volumio volumio[826]: info: sendMpdCommand status took 1 milliseconds Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:17 volumio volumio[826]: info: sendMpdCommand status took 2 milliseconds Oct 23 17:21:17 volumio volumio[826]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 23 17:21:17 volumio volumio[826]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:17 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:17 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:17 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:17 volumio volumio[826]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3592,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:17 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::syncState stateService pause Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus pause Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:17 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 17:21:17 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::stPlaybackTimer Oct 23 17:21:17 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:17 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:17 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:17 volumio volumio[826]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3592,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:17 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::syncState stateService pause Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus pause Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:17 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::stPlaybackTimer Oct 23 17:21:17 volumio volumio[826]: info: ------------------------------ 14ms Oct 23 17:21:17 volumio volumio[826]: info: ------------------------------ 14ms Oct 23 17:21:17 volumio volumio[826]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 23 17:21:17 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:17 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:17 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:17 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:17 volumio volumio[826]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3592,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:17 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::syncState stateService pause Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus pause Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:17 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:17 volumio volumio[826]: info: CoreStateMachine::stPlaybackTimer Oct 23 17:21:17 volumio volumio[826]: info: ------------------------------ 24ms Oct 23 17:21:17 volumio volumio[826]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 23 17:21:17 volumio ntpd[1285]: 85.199.214.99 local addr 192.168.1.12 -> Oct 23 17:21:20 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 17:21:20 volumio volumio[826]: info: Preload queue cleared Oct 23 17:21:26 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 17:21:26 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 17:21:26 volumio volumio[826]: info: Discovery: Getting this device information Oct 23 17:21:26 volumio volumio[826]: info: CoreCommandRouter::volumioGetState Oct 23 17:21:26 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 17:21:27 volumio volumio[826]: info: Preload queue cleared Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::ClearQueue Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::updateTrackBlock Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrackBlock Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::stPlaybackTimer Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::serviceStop Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::serviceStop Oct 23 17:21:27 volumio volumio[826]: info: [1729696887907] ControllerUPNPBrowser::stop Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand stop Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::clearPlayQueue Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::addQueueItems Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::addQueueItems Oct 23 17:21:27 volumio volumio[826]: info: Preload queue cleared Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42310 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42310 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42314 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42314 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42313 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42313 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@578662 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@578662 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623607 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623607 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623595 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623595 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623596 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623596 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623606 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623606 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623599 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623599 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623608 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623608 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623610 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623610 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623609 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623609 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623613 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623613 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623601 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623601 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623600 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623600 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623612 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623612 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623597 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623597 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623594 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623594 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623598 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623598 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623611 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623611 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623626 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623626 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623628 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623628 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623632 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623632 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623619 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623619 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623624 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623624 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623627 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623627 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623615 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623615 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623616 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623616 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623614 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623614 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623625 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623625 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623629 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623629 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623617 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623617 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623623 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623623 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623631 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623631 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623622 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623622 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623620 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623620 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623578 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623578 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623566 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623566 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623567 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623567 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623591 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623591 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623592 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623592 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623569 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623569 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623570 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623570 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623593 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623593 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623584 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623584 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623572 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623572 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623585 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623585 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623573 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623573 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623582 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623582 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623574 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623574 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623590 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623590 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623581 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623581 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623576 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623576 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623554 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623554 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623580 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623580 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623555 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623555 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623579 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623579 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623556 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623556 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623557 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623557 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623577 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623577 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623558 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623558 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623583 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623583 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623559 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623559 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623588 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623588 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623587 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623587 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623561 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623561 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623562 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623562 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623589 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623589 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623563 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623563 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623586 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623586 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623564 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623564 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623565 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623565 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623658 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623658 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623675 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623675 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623662 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623662 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623655 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623655 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623646 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623646 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623657 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623657 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623668 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623668 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623647 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623647 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623635 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623635 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623652 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623652 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623653 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623653 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623654 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623654 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623633 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623633 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623671 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623671 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623636 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623636 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623656 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623656 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623677 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623677 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623670 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623670 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623650 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623650 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623645 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623645 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623637 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623637 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623672 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623672 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623642 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623642 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623644 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623644 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623669 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623669 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623674 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623674 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623634 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623634 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623648 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623648 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623663 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623663 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623640 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623640 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623651 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623651 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623643 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623643 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623639 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623639 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623659 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623659 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623667 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623667 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623676 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623676 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623638 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623638 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623641 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623641 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623666 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623666 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623660 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623660 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623673 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623673 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623649 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623649 Oct 23 17:21:27 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623665 Oct 23 17:21:27 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623665 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::updateTrackBlock Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrackBlock Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPlay Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::play index 61 Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::play index undefined Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 61 Oct 23 17:21:27 volumio volumio[826]: info: Oct 23 17:21:27 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:27 volumio volumio[826]: info: sendMpdCommand stop took 42 milliseconds Oct 23 17:21:27 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:27 volumio volumio[826]: info: Oct 23 17:21:27 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:27 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:27 volumio volumio[826]: info: Oct 23 17:21:27 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 23 17:21:27 volumio volumio[826]: info: ControllerMpd::getState Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 23 17:21:27 volumio volumio[826]: info: sendMpdCommand status took 7 milliseconds Oct 23 17:21:27 volumio volumio[826]: info: sendMpdCommand status took 6 milliseconds Oct 23 17:21:27 volumio volumio[826]: info: sendMpdCommand status took 4 milliseconds Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 17:21:27 volumio volumio[826]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 23 17:21:27 volumio volumio[826]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 23 17:21:27 volumio volumio[826]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:27 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 23 17:21:27 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:27 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::syncState stateService stop Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus stop Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:27 volumio volumio[826]: info: No code Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:27 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:27 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::syncState stateService stop Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus stop Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:27 volumio volumio[826]: info: No code Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:27 volumio volumio[826]: info: ControllerMpd::pushState Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=623719.wav?format=WAV&downsample=true","artist":null,"album":null,"uri":"http://192.168.1.3:50002/transcoder/genericoder.cgi/id=623719.wav?format=WAV&downsample=true","trackType":""} Oct 23 17:21:27 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::syncState stateService stop Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus stop Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:27 volumio volumio[826]: info: No code Oct 23 17:21:27 volumio volumio[826]: info: CoreStateMachine::pushState Oct 23 17:21:27 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:27 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 23 17:21:28 volumio volumio[826]: info: ------------------------------ 54ms Oct 23 17:21:28 volumio volumio[826]: info: ------------------------------ 52ms Oct 23 17:21:28 volumio volumio[826]: info: ------------------------------ 51ms Oct 23 17:21:46 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 17:21:46 volumio volumio[826]: info: Preload queue cleared Oct 23 17:21:46 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@578475 Oct 23 17:21:46 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@578475 in service upnp_browser Oct 23 17:21:46 volumio volumio[826]: info: No valid Plugin REST Endpoint Oct 23 17:21:46 volumio volumio[826]: info: No valid Plugin REST Endpoint Oct 23 17:21:52 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 17:21:52 volumio volumio[826]: info: Preload queue cleared Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@620368 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623485 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623486 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623487 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623488 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623489 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623490 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623491 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623492 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623493 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623494 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623495 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623496 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623497 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623498 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623499 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623500 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623501 Oct 23 17:21:52 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623502 Oct 23 17:21:52 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@620368 in service upnp_browser Oct 23 17:21:52 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623485 in service upnp_browser Oct 23 17:21:52 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623486 in service upnp_browser Oct 23 17:21:52 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623487 in service upnp_browser Oct 23 17:21:52 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623488 in service upnp_browser Oct 23 17:21:52 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623489 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623490 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623491 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623492 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623493 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623494 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623495 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623496 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623497 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623498 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623499 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623500 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623501 in service upnp_browser Oct 23 17:21:53 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623502 in service upnp_browser Oct 23 17:21:56 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 17:21:56 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 17:21:56 volumio volumio[826]: info: Discovery: Getting this device information Oct 23 17:21:56 volumio volumio[826]: info: CoreCommandRouter::volumioGetState Oct 23 17:21:56 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 23 17:21:56 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 17:22:01 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 17:22:01 volumio volumio[826]: info: Preload queue cleared Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623503 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623504 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623505 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623506 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623507 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623508 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623509 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623510 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623511 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623512 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623513 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623514 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623515 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623516 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623517 Oct 23 17:22:01 volumio volumio[826]: info: Preloading song: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623518 Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623503 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623504 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623505 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623506 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623507 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623508 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623509 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623510 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623511 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623512 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623513 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623514 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623515 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623516 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623517 in service upnp_browser Oct 23 17:22:01 volumio volumio[826]: info: Exploding uri upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623518 in service upnp_browser Oct 23 17:22:09 volumio volumio[826]: info: Preload queue cleared Oct 23 17:22:09 volumio volumio[826]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::ClearQueue Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:09 volumio volumio[826]: info: CorePlayQueue::clearPlayQueue Oct 23 17:22:09 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:22:09 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::addQueueItems Oct 23 17:22:09 volumio volumio[826]: info: CorePlayQueue::addQueueItems Oct 23 17:22:09 volumio volumio[826]: info: Preload queue cleared Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42305 Oct 23 17:22:09 volumio volumio[826]: info: Exploding uri upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42305 in service upnp_browser Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623503 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623503 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623504 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623504 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623505 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623505 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623506 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623506 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623507 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623507 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623508 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623508 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623509 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623509 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623510 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623510 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623511 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623511 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623512 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623512 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623513 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623513 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623514 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623514 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623515 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623515 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623516 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623516 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623517 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623517 Oct 23 17:22:09 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623518 Oct 23 17:22:09 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623518 Oct 23 17:22:09 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:22:09 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::updateTrackBlock Oct 23 17:22:09 volumio volumio[826]: info: CorePlayQueue::getTrackBlock Oct 23 17:22:09 volumio volumio[826]: info: CoreCommandRouter::volumioPlay Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::play index 2 Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::play index undefined Oct 23 17:22:09 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:09 volumio volumio[826]: info: CorePlayQueue::getTrack 2 Oct 23 17:22:13 volumio volumio[826]: info: Preload queue cleared Oct 23 17:22:13 volumio volumio[826]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::ClearQueue Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:13 volumio volumio[826]: info: CorePlayQueue::clearPlayQueue Oct 23 17:22:13 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:22:13 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::addQueueItems Oct 23 17:22:13 volumio volumio[826]: info: CorePlayQueue::addQueueItems Oct 23 17:22:13 volumio volumio[826]: info: Preload queue cleared Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42305 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42305 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623503 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623503 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623504 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623504 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623505 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623505 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623506 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623506 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623507 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623507 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623508 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623508 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623509 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623509 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623510 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623510 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623511 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623511 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623512 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623512 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623513 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623513 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623514 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623514 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623515 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623515 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623516 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623516 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623517 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623517 Oct 23 17:22:13 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623518 Oct 23 17:22:13 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623518 Oct 23 17:22:13 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:22:13 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::updateTrackBlock Oct 23 17:22:13 volumio volumio[826]: info: CorePlayQueue::getTrackBlock Oct 23 17:22:13 volumio volumio[826]: info: CoreCommandRouter::volumioPlay Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::play index 2 Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::play index undefined Oct 23 17:22:13 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:13 volumio volumio[826]: info: CorePlayQueue::getTrack 2 Oct 23 17:22:19 volumio volumio[826]: info: Preload queue cleared Oct 23 17:22:19 volumio volumio[826]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::ClearQueue Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:19 volumio volumio[826]: info: CorePlayQueue::clearPlayQueue Oct 23 17:22:19 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:22:19 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::addQueueItems Oct 23 17:22:19 volumio volumio[826]: info: CorePlayQueue::addQueueItems Oct 23 17:22:19 volumio volumio[826]: info: Preload queue cleared Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42305 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/folder/http://192.168.1.3:50001/ContentDirectory/control@22$42305 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623503 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623503 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623504 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623504 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623505 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623505 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623506 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623506 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623507 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623507 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623508 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623508 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623509 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623509 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623510 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623510 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623511 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623511 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623512 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623512 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623513 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623513 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623514 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623514 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623515 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623515 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623516 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623516 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623517 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623517 Oct 23 17:22:19 volumio volumio[826]: info: Adding Item to queue: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623518 Oct 23 17:22:19 volumio volumio[826]: info: Using cached record of: upnp/http://192.168.1.3:50001/ContentDirectory/control@22$@623518 Oct 23 17:22:19 volumio volumio[826]: info: CoreCommandRouter::volumioPushQueue Oct 23 17:22:19 volumio volumio[826]: info: CorePlayQueue::saveQueue Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::updateTrackBlock Oct 23 17:22:19 volumio volumio[826]: info: CorePlayQueue::getTrackBlock Oct 23 17:22:19 volumio volumio[826]: info: CoreCommandRouter::volumioPlay Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::play index 2 Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::stop Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::play index undefined Oct 23 17:22:19 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 17:22:19 volumio volumio[826]: info: CorePlayQueue::getTrack 2 Oct 23 17:22:25 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 17:22:25 volumio volumio[826]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 17:22:25 volumio volumio[826]: TypeError: Cannot read property 'length' of undefined Oct 23 17:22:25 volumio volumio[826]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 23 17:22:25 volumio volumio[826]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 23 17:22:25 volumio volumio[826]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 23 17:22:25 volumio volumio[826]: at Parser.emit (events.js:315:20) Oct 23 17:22:25 volumio volumio[826]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 23 17:22:25 volumio volumio[826]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 23 17:22:25 volumio volumio[826]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 23 17:22:25 volumio volumio[826]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 23 17:22:25 volumio volumio[826]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 23 17:22:25 volumio volumio[826]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 23 17:22:25 volumio volumio[826]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 23 17:22:25 volumio volumio[826]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 23 17:22:25 volumio volumio[826]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 23 17:22:25 volumio volumio[826]: at IncomingMessage.emit (events.js:327:22) Oct 23 17:22:25 volumio volumio[826]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 23 17:22:25 volumio volumio[826]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 23 17:22:25 volumio volumio[826]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 17:22:25 volumio sudo[4598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-23 17:21 Oct 23 17:22:25 volumio sudo[4598]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"