-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Sun 2025-01-19 13:57:06 UTC. -- Jan 19 13:56:03 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::ClearQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::stop Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::clearPlayQueue Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::saveQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::addQueueItems Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::addQueueItems Jan 19 13:56:03 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$29 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$29 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$28 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$28 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$27 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$27 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$26 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$26 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$25 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$25 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$24 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$24 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$23 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$23 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$22 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$22 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$21 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$21 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$20 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$20 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1F Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1F Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1E Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1E Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1D Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1D Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1C Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1C Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1B Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1B Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1A Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1A Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$19 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$19 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$18 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$18 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$17 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$17 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$16 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$16 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$15 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$15 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$14 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$14 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$13 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$13 Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::saveQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::updateTrackBlock Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::getTrackBlock Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::play index 22 Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::addQueueItems Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::addQueueItems Jan 19 13:56:03 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$12 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$12 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$11 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$11 Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::stop Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::saveQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::play index undefined Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::updateTrackBlock Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::getTrackBlock Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::getTrack 22 Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::startPlaybackTimer Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::getTrack 22 Jan 19 13:56:03 volumio volumio[1440]: info: [1737294963447] ControllerUPNPBrowser::clearAddPlayTrack Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 13:56:03 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::ClearQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::stop Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::clearPlayQueue Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::saveQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::addQueueItems Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::addQueueItems Jan 19 13:56:03 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$29 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$29 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$28 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$28 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$27 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$27 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$26 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$26 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$25 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$25 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$24 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$24 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$23 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$23 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$22 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$22 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$21 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$21 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$20 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$20 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1F Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1F Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1E Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1E Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1D Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1D Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1C Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1C Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1B Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1B Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1A Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$1A Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$19 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$19 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$18 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$18 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$17 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$17 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$16 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$16 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$15 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$15 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$14 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$14 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$13 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$13 Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::saveQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::updateTrackBlock Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::getTrackBlock Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::play index 22 Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::addQueueItems Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::addQueueItems Jan 19 13:56:03 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$12 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$12 Jan 19 13:56:03 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$11 Jan 19 13:56:03 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$FF0$64$0$3$0$0$11 Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::stop Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::saveQueue Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::play index undefined Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::updateTrackBlock Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::getTrackBlock Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::getTrack 22 Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::startPlaybackTimer Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::getTrack 22 Jan 19 13:56:03 volumio volumio[1440]: info: [1737294963472] ControllerUPNPBrowser::clearAddPlayTrack Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand stop took 31 milliseconds Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand stop took 7 milliseconds Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 13:56:03 volumio volumio[1440]: info: Jan 19 13:56:03 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Jan 19 13:56:03 volumio volumio[1440]: info: Ignoring MPD Status Update Jan 19 13:56:03 volumio volumio[1440]: info: Jan 19 13:56:03 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Jan 19 13:56:03 volumio volumio[1440]: info: Ignoring MPD Status Update Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand clear took 2 milliseconds Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand clear took 2 milliseconds Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.47:8200/MediaItems/2047.flac" Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.47:8200/MediaItems/2047.flac" Jan 19 13:56:03 volumio volumio[1440]: error: updateQueue error: null Jan 19 13:56:03 volumio volumio[1440]: error: updateQueue error: null Jan 19 13:56:03 volumio volumio[1440]: info: ------------------------------ 3ms Jan 19 13:56:03 volumio volumio[1440]: info: ------------------------------ 3ms Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.47:8200/MediaItems/2047.flac" Jan 19 13:56:03 volumio volumio[1440]: info: Jan 19 13:56:03 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Jan 19 13:56:03 volumio volumio[1440]: info: Ignoring MPD Status Update Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.47:8200/MediaItems/2047.flac" Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand add "http://192.168.1.47:8200/MediaItems/2047.flac" took 5 milliseconds Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand play Jan 19 13:56:03 volumio volumio[1440]: info: Jan 19 13:56:03 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Jan 19 13:56:03 volumio volumio[1440]: info: Ignoring MPD Status Update Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand add "http://192.168.1.47:8200/MediaItems/2047.flac" took 3 milliseconds Jan 19 13:56:03 volumio volumio[1440]: info: ------------------------------ 3ms Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand play Jan 19 13:56:03 volumio volumio[1440]: info: Jan 19 13:56:03 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Jan 19 13:56:03 volumio volumio[1440]: info: Ignoring MPD Status Update Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand play took 7 milliseconds Jan 19 13:56:03 volumio volumio[1440]: info: ------------------------------ 5ms Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand play took 5 milliseconds Jan 19 13:56:03 volumio volumio[1440]: info: Jan 19 13:56:03 volumio volumio[1440]: ---------------------------- MPD announces state update: player Jan 19 13:56:03 volumio volumio[1440]: info: ControllerMpd::getState Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand status Jan 19 13:56:03 volumio volumio[1440]: info: ------------------------------ 4ms Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand status took 8 milliseconds Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::parseState Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand clearerror took 2 milliseconds Jan 19 13:56:03 volumio volumio[1440]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 19 13:56:03 volumio volumio[1440]: verbose: ControllerMpd::parseTrackInfo Jan 19 13:56:03 volumio volumio[1440]: info: ControllerMpd::pushState Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::servicePushState Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::pushState Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPushState Jan 19 13:56:03 volumio volumio[1440]: info: CorePlayQueue::getTrack 22 Jan 19 13:56:03 volumio volumio[1440]: 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":"2047.flac","artist":null,"album":null,"uri":"http://192.168.1.47:8200/MediaItems/2047.flac","trackType":"flac"} Jan 19 13:56:03 volumio volumio[1440]: verbose: CURRENT POSITION 22 Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::syncState stateService stop Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::syncState currentStatus stop Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::pushState Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPushState Jan 19 13:56:03 volumio volumio[1440]: info: No code Jan 19 13:56:03 volumio volumio[1440]: info: CoreStateMachine::pushState Jan 19 13:56:03 volumio volumio[1440]: info: CoreCommandRouter::volumioPushState Jan 19 13:56:03 volumio volumio[1440]: info: ------------------------------ 31ms Jan 19 13:56:03 volumio volumio[1440]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.RZr89nG1kGSksGeVYhWyoxjv7Xh1.51346f55d7efd62a95ba2a47aa712858.state.status' Jan 19 13:56:03 volumio volumio[1440]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.RZr89nG1kGSksGeVYhWyoxjv7Xh1.51346f55d7efd62a95ba2a47aa712858.state.status' Jan 19 13:56:12 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 13:56:12 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:17 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 13:56:17 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$11 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$12 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$13 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$14 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$15 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$16 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$17 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$18 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$19 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1A Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1B Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1C Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1D Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1E Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1F Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$20 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$21 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$22 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$23 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$24 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$25 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$26 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$27 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$28 Jan 19 13:56:17 volumio volumio[1440]: info: Preloading song: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$29 Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$11 in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$12 in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$13 in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Executing endpoint getSimilarAlbums Jan 19 13:56:17 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$14 in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$15 in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Executing endpoint metavolumio Jan 19 13:56:17 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 19 13:56:17 volumio volumio[1440]: info: Executing endpoint metavolumio Jan 19 13:56:17 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$16 in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$17 in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$18 in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$19 in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1A in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1B in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1C in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1D in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1E in service upnp_browser Jan 19 13:56:17 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1F in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$20 in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$21 in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$22 in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$23 in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$24 in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$25 in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$26 in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$27 in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$28 in service upnp_browser Jan 19 13:56:18 volumio volumio[1440]: info: Exploding uri upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$29 in service upnp_browser Jan 19 13:56:20 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::ClearQueue Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::stop Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::clearPlayQueue Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::saveQueue Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::addQueueItems Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::addQueueItems Jan 19 13:56:20 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$11 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$11 Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::saveQueue Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::updateTrackBlock Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::getTrackBlock Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::play index 0 Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::addQueueItems Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::addQueueItems Jan 19 13:56:20 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$12 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$12 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$13 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$13 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$14 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$14 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$15 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$15 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$16 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$16 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$17 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$17 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$18 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$18 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$19 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$19 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1A Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1A Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1B Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1B Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1C Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1C Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1D Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1D Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1E Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1E Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1F Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$1F Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$20 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$20 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$21 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$21 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$22 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$22 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$23 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$23 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$24 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$24 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$25 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$25 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$26 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$26 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$27 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$27 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$28 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$28 Jan 19 13:56:20 volumio volumio[1440]: info: Adding Item to queue: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$29 Jan 19 13:56:20 volumio volumio[1440]: info: Using cached record of: upnp/http://192.168.1.47:8200/ctl/ContentDir@1$7$45$29 Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::stop Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::saveQueue Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::play index undefined Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::updateTrackBlock Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::getTrackBlock Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::startPlaybackTimer Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Jan 19 13:56:20 volumio volumio[1440]: info: [1737294980619] ControllerUPNPBrowser::clearAddPlayTrack Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 13:56:20 volumio volumio[1440]: info: sendMpdCommand stop took 3 milliseconds Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 13:56:20 volumio volumio[1440]: info: Jan 19 13:56:20 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Jan 19 13:56:20 volumio volumio[1440]: info: Ignoring MPD Status Update Jan 19 13:56:20 volumio volumio[1440]: info: sendMpdCommand clear took 1 milliseconds Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.47:8200/MediaItems/2039.flac" Jan 19 13:56:20 volumio volumio[1440]: error: updateQueue error: null Jan 19 13:56:20 volumio volumio[1440]: info: ------------------------------ 3ms Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.47:8200/MediaItems/2039.flac" Jan 19 13:56:20 volumio volumio[1440]: info: Jan 19 13:56:20 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Jan 19 13:56:20 volumio volumio[1440]: info: Ignoring MPD Status Update Jan 19 13:56:20 volumio volumio[1440]: info: sendMpdCommand add "http://192.168.1.47:8200/MediaItems/2039.flac" took 1 milliseconds Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand play Jan 19 13:56:20 volumio volumio[1440]: info: ------------------------------ 3ms Jan 19 13:56:20 volumio volumio[1440]: info: sendMpdCommand play took 1 milliseconds Jan 19 13:56:20 volumio volumio[1440]: info: Jan 19 13:56:20 volumio volumio[1440]: ---------------------------- MPD announces state update: player Jan 19 13:56:20 volumio volumio[1440]: info: ControllerMpd::getState Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand status Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 19 13:56:20 volumio volumio[1440]: info: sendMpdCommand status took 5 milliseconds Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::parseState Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 13:56:20 volumio volumio[1440]: info: sendMpdCommand clearerror took 1 milliseconds Jan 19 13:56:20 volumio volumio[1440]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 19 13:56:20 volumio volumio[1440]: verbose: ControllerMpd::parseTrackInfo Jan 19 13:56:20 volumio volumio[1440]: info: ControllerMpd::pushState Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::servicePushState Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::pushState Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::volumioPushState Jan 19 13:56:20 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Jan 19 13:56:20 volumio volumio[1440]: 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":"2039.flac","artist":null,"album":null,"uri":"http://192.168.1.47:8200/MediaItems/2039.flac","trackType":"flac"} Jan 19 13:56:20 volumio volumio[1440]: verbose: CURRENT POSITION 0 Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::syncState stateService stop Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::syncState currentStatus stop Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::pushState Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::volumioPushState Jan 19 13:56:20 volumio volumio[1440]: info: No code Jan 19 13:56:20 volumio volumio[1440]: info: CoreStateMachine::pushState Jan 19 13:56:20 volumio volumio[1440]: info: CoreCommandRouter::volumioPushState Jan 19 13:56:20 volumio volumio[1440]: info: ------------------------------ 27ms Jan 19 13:56:20 volumio volumio[1440]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.RZr89nG1kGSksGeVYhWyoxjv7Xh1.51346f55d7efd62a95ba2a47aa712858.state.status' Jan 19 13:56:20 volumio volumio[1440]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.RZr89nG1kGSksGeVYhWyoxjv7Xh1.51346f55d7efd62a95ba2a47aa712858.state.status' Jan 19 13:56:50 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 13:56:50 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 13:56:50 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:56:50 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:57:03 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 13:57:03 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 13:57:03 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:57:03 volumio volumio[1440]: info: Preload queue cleared Jan 19 13:57:06 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 13:57:06 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 19 13:57:06 volumio volumio[1440]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 13:57:06 volumio volumio[1440]: TypeError: Cannot read property 'length' of undefined Jan 19 13:57:06 volumio volumio[1440]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 19 13:57:06 volumio volumio[1440]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 19 13:57:06 volumio volumio[1440]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 19 13:57:06 volumio volumio[1440]: at Parser.emit (events.js:400:28) Jan 19 13:57:06 volumio volumio[1440]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 19 13:57:06 volumio volumio[1440]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 19 13:57:06 volumio volumio[1440]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 19 13:57:06 volumio volumio[1440]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 19 13:57:06 volumio volumio[1440]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 19 13:57:06 volumio volumio[1440]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 19 13:57:06 volumio volumio[1440]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 19 13:57:06 volumio volumio[1440]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 19 13:57:06 volumio volumio[1440]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 19 13:57:06 volumio volumio[1440]: at IncomingMessage.emit (events.js:412:35) Jan 19 13:57:06 volumio volumio[1440]: at endReadableNT (internal/streams/readable.js:1333:12) Jan 19 13:57:06 volumio volumio[1440]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 19 13:57:06 volumio volumio[1440]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 13:57:06 volumio sudo[15500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 13:56 Jan 19 13:57:06 volumio sudo[15500]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:11:38 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="932fc3546f7cc8faa2b4d349036752dd"