Oct 11 23:48:13 volumio volumio[1158]: info: Preload queue cleared Oct 11 23:48:13 volumio volumio[1158]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::ClearQueue Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::stop Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:48:13 volumio volumio[1158]: info: CorePlayQueue::clearPlayQueue Oct 11 23:48:13 volumio volumio[1158]: info: CorePlayQueue::saveQueue Oct 11 23:48:13 volumio volumio[1158]: info: CoreCommandRouter::volumioPushQueue Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::addQueueItems Oct 11 23:48:13 volumio volumio[1158]: info: CorePlayQueue::addQueueItems Oct 11 23:48:13 volumio volumio[1158]: info: Preload queue cleared Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14603 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14603 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14614 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14614 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14595 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14595 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14610 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14610 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14602 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14602 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14590 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14590 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14635 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14635 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14566 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14566 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14579 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14579 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14622 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14622 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14629 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14629 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14644 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14644 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14613 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14613 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14638 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14638 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14641 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14641 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14643 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14643 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14581 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14581 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14587 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14587 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14585 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14585 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14578 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14578 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14567 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14567 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14562 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14562 Oct 11 23:48:13 volumio volumio[1158]: info: CoreCommandRouter::volumioPushQueue Oct 11 23:48:13 volumio volumio[1158]: info: CorePlayQueue::saveQueue Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::updateTrackBlock Oct 11 23:48:13 volumio volumio[1158]: info: CorePlayQueue::getTrackBlock Oct 11 23:48:13 volumio volumio[1158]: info: CoreCommandRouter::volumioPlay Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::play index 21 Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::addQueueItems Oct 11 23:48:13 volumio volumio[1158]: info: CorePlayQueue::addQueueItems Oct 11 23:48:13 volumio volumio[1158]: info: Preload queue cleared Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14573 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14573 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14599 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14599 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14651 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14651 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14611 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14611 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14652 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14652 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14640 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14640 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14563 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14563 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14568 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14568 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14630 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14630 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14633 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14633 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14612 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14612 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14606 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14606 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14621 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14621 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14618 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14618 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14639 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14639 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14560 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14560 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14572 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14572 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14559 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14559 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14653 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14653 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14601 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14601 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14589 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14589 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14626 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14626 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14627 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14627 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14632 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14632 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14609 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14609 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14637 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14637 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14598 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14598 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14580 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14580 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14594 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14594 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14656 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14656 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14588 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14588 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14636 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14636 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14571 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14571 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14564 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14564 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14623 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14623 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14645 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14645 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14631 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14631 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14569 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14569 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14557 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14557 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14634 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14634 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14649 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14649 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14577 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14577 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14648 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14648 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14582 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14582 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14600 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14600 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14591 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14591 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14561 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14561 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14570 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14570 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14654 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14654 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14628 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14628 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14607 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14607 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14616 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14616 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14647 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14647 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14604 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14604 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14586 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14586 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14646 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14646 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14558 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14558 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14575 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14575 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14593 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14593 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14655 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14655 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14596 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14596 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14597 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14597 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14624 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14624 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14625 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14625 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14574 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14574 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14642 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14642 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14584 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14584 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14617 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14617 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14605 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14605 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14565 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14565 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14650 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14650 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14619 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14619 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14615 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14615 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14608 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14608 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14576 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14576 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14592 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14592 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14620 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14620 Oct 11 23:48:13 volumio volumio[1158]: info: Adding Item to queue: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14583 Oct 11 23:48:13 volumio volumio[1158]: info: Using cached record of: upnp/http://192.168.1.2:50001/ContentDirectory/control@22$@14583 Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::stop Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:48:13 volumio volumio[1158]: info: CoreCommandRouter::volumioPushQueue Oct 11 23:48:13 volumio volumio[1158]: info: CorePlayQueue::saveQueue Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::play index undefined Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:48:13 volumio volumio[1158]: info: CoreStateMachine::updateTrackBlock Oct 11 23:48:13 volumio volumio[1158]: info: CorePlayQueue::getTrackBlock Oct 11 23:48:13 volumio volumio[1158]: info: CorePlayQueue::getTrack 21 Oct 11 23:49:26 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:26.007+09:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s Oct 11 23:49:26 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:26.640+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=632.514205ms Oct 11 23:49:26 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:26.653+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=http://pushupdates.volumio.org duration=642.655957ms Oct 11 23:49:26 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:26.807+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://www.googleapis.com duration=795.046905ms Oct 11 23:49:26 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:26.947+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://securetoken.googleapis.com duration=940.115875ms Oct 11 23:49:27 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:27.023+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=1.012865983s Oct 11 23:49:27 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:27.235+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=http://plugins.volumio.org duration=1.224972966s Oct 11 23:49:27 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:27.314+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=1.307140611s Oct 11 23:49:27 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:27.389+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://google.com duration=1.379475983s Oct 11 23:49:27 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:27.391+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=1.381950705s Oct 11 23:49:27 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:27.400+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://database.volumio.cloud duration=1.388022746s Oct 11 23:49:27 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:49:27 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:49:27 volumio volumio[1158]: info: Discovery: Getting this device information Oct 11 23:49:27 volumio volumio[1158]: info: CoreCommandRouter::volumioGetState Oct 11 23:49:27 volumio volumio[1158]: info: CorePlayQueue::getTrack 0 Oct 11 23:49:27 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:49:27 volumio volumio[1158]: verbose: New Socket.io Connection to 192.168.1.206:3000 from 192.168.1.142 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 11 23:49:27 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 23:49:27 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 23:49:28 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:28.431+09:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=http://cddb.volumio.org duration=2.417565951s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 11 23:49:29 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:29.141+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://functions.volumio.cloud duration=3.128106058s Oct 11 23:49:29 volumio volumio5-onboarding[1800]: time=2026-10-11T23:49:29.163+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.142:37290 @ 0x3095e90" latency=29.904849ms timeout=10s endpoint=https://functions.volumio.cloud duration=3.155047533s Oct 11 23:49:34 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 11 23:49:34 volumio volumio[1158]: info: Preload queue cleared Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:49:48 volumio volumio[1158]: info: Retrieving Cloud Streaming UI Oct 11 23:49:48 volumio volumio[1158]: info: Getting Tidal Cloud Configuration Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:49:48 volumio volumio[1158]: info: Getting Qobuz Cloud Configuration Oct 11 23:49:48 volumio volumio[1158]: info: Asking plugin for UI Config Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:49:48 volumio volumio[1158]: info: Getting Spotify Cloud Configuration Oct 11 23:49:48 volumio volumio[1158]: info: Asking plugin for UI Config Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:49:48 volumio volumio[1158]: info: Saving Spotify Acccount Oct 11 23:49:48 volumio volumio[1158]: info: Got Tidal Cloud Configuration Oct 11 23:49:48 volumio volumio[1158]: info: Got it Oct 11 23:49:48 volumio volumio[1158]: info: Got it Oct 11 23:49:48 volumio volumio[1158]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 23:49:48 volumio volumio[1158]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 23:49:48 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 11 23:49:52 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 11 23:49:52 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 11 23:49:52 volumio volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 11 23:49:53 volumio volumio[1158]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:49:53 volumio volumio[1158]: Error: getaddrinfo -3007 Oct 11 23:49:53 volumio volumio[1158]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Oct 11 23:49:53 volumio volumio[1158]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Oct 11 23:49:53 volumio volumio[1158]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Oct 11 23:49:53 volumio volumio[1158]: code: -3007, Oct 11 23:49:53 volumio volumio[1158]: errno: -3007, Oct 11 23:49:53 volumio volumio[1158]: syscall: 'getaddrinfo' Oct 11 23:49:53 volumio volumio[1158]: } Oct 11 23:49:53 volumio volumio[1158]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:49:54 volumio sudo[9173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 23:48' Oct 11 23:49:54 volumio sudo[9173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"