-- Logs begin at Thu 2019-02-14 19:12:00 JST, end at Sat 2025-02-22 21:20:44 JST. -- Feb 22 21:19:07 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 22 21:19:07 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:09 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 22 21:19:09 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$0 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$1 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$2 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$3 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$4 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$5 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$6 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$7 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$8 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$9 Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$A Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$B Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$C Feb 22 21:19:09 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$D Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$0 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$1 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$2 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$3 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$4 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$5 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$6 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$7 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$8 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$9 in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$A in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$B in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$C in service upnp_browser Feb 22 21:19:10 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$1$0$D in service upnp_browser Feb 22 21:19:23 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 22 21:19:23 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$0 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$1 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$2 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$3 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$4 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$5 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$6 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$7 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$8 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$9 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$A Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$B Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$C Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$D Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$E Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$F Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$10 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$11 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$12 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$13 Feb 22 21:19:23 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$14 Feb 22 21:19:23 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$0 in service upnp_browser Feb 22 21:19:23 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$1 in service upnp_browser Feb 22 21:19:23 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$2 in service upnp_browser Feb 22 21:19:23 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$3 in service upnp_browser Feb 22 21:19:23 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$4 in service upnp_browser Feb 22 21:19:23 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$5 in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$6 in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$7 in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$8 in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$9 in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$A in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$B in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$C in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$D in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$E in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$F in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$10 in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$11 in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$12 in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$13 in service upnp_browser Feb 22 21:19:24 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$14 in service upnp_browser Feb 22 21:19:28 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::ClearQueue Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::stop Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::stPlaybackTimer Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::updateTrackBlock Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrackBlock Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::serviceStop Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::serviceStop Feb 22 21:19:28 volumio volumio[960]: info: [1740226768683] ControllerUPNPBrowser::stop Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::clearPlayQueue Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::saveQueue Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPushQueue Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::addQueueItems Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::addQueueItems Feb 22 21:19:28 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$0 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$0 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$1 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$1 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$2 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$2 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$3 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$3 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$4 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$4 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$5 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$5 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$6 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$6 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$7 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$7 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$8 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$8 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$9 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$9 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$A Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$A Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$B Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$B Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$C Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$C Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$D Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$D Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$E Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$E Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$F Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$F Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$10 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$10 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$11 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$11 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$12 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$12 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$13 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$13 Feb 22 21:19:28 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$14 Feb 22 21:19:28 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$B$14 Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPushQueue Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::saveQueue Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::updateTrackBlock Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrackBlock Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPlay Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::play index 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::stop Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::play index undefined Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::startPlaybackTimer Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: info: [1740226768764] ControllerUPNPBrowser::clearAddPlayTrack Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 21:19:28 volumio volumio[960]: info: Feb 22 21:19:28 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:28 volumio volumio[960]: info: sendMpdCommand stop took 95 milliseconds Feb 22 21:19:28 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:28 volumio volumio[960]: info: sendMpdCommand stop took 17 milliseconds Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 21:19:28 volumio volumio[960]: info: Feb 22 21:19:28 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:28 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:28 volumio volumio[960]: info: Feb 22 21:19:28 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:28 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:28 volumio volumio[960]: info: Feb 22 21:19:28 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:28 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:28 volumio volumio[960]: info: sendMpdCommand status took 19 milliseconds Feb 22 21:19:28 volumio volumio[960]: info: sendMpdCommand clear took 17 milliseconds Feb 22 21:19:28 volumio volumio[960]: info: sendMpdCommand status took 14 milliseconds Feb 22 21:19:28 volumio volumio[960]: info: sendMpdCommand status took 12 milliseconds Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.1:8200/MediaItems/582.flac" Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:28 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 21:19:28 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::syncState stateService stop Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:28 volumio volumio[960]: info: No code Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:28 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 21:19:28 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::syncState stateService stop Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:28 volumio volumio[960]: info: No code Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:28 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:28 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:28 volumio volumio[960]: info: ------------------------------ 136ms Feb 22 21:19:28 volumio volumio[960]: info: ------------------------------ 135ms Feb 22 21:19:28 volumio volumio[960]: info: Feb 22 21:19:28 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:28 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:28 volumio volumio[960]: info: Feb 22 21:19:28 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:28 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:28 volumio volumio[960]: error: updateQueue error: null Feb 22 21:19:28 volumio volumio[960]: error: updateQueue error: null Feb 22 21:19:28 volumio volumio[960]: error: updateQueue error: null Feb 22 21:19:28 volumio volumio[960]: info: ------------------------------ 137ms Feb 22 21:19:28 volumio volumio[960]: info: sendMpdCommand playlistinfo took 131 milliseconds Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.1:8200/MediaItems/582.flac" Feb 22 21:19:28 volumio volumio[960]: info: ------------------------------ 12ms Feb 22 21:19:28 volumio volumio[960]: info: ------------------------------ 10ms Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:28 volumio volumio[960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 22 21:19:28 volumio volumio[960]: info: ------------------------------ 163ms Feb 22 21:19:28 volumio volumio[960]: info: Feb 22 21:19:28 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:28 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:28 volumio volumio[960]: info: sendMpdCommand add "http://192.168.1.1:8200/MediaItems/582.flac" took 22 milliseconds Feb 22 21:19:28 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 22 21:19:28 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand play Feb 22 21:19:28 volumio volumio[960]: info: Feb 22 21:19:28 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:28 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:28 volumio volumio[960]: info: Feb 22 21:19:28 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:28 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 46ms Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand play took 43 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 42ms Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 40ms Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand status took 17 milliseconds Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand status took 21 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand status took 19 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:29 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"582.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/582.flac","trackType":"flac"} Feb 22 21:19:29 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 43ms Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand status took 30 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:29 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"582.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/582.flac","trackType":"flac"} Feb 22 21:19:29 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:29 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:29 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"582.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/582.flac","trackType":"flac"} Feb 22 21:19:29 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:29 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 137ms Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 136ms Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:29 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand status took 101 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand status took 98 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand playlistinfo took 92 milliseconds Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:29 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"わかれうた","artist":"中島みゆき","album":"大吟醸","uri":"http://192.168.1.1:8200/MediaItems/582.flac","trackType":"flac"} Feb 22 21:19:29 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:29 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 359ms Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:29 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:29 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:29 volumio volumio[960]: info: Feb 22 21:19:29 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 326ms Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand status took 324 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand playlistinfo took 324 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand playlistinfo took 327 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 100ms Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand status took 98 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 97ms Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand status took 97 milliseconds Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:29 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"わかれうた","artist":"中島みゆき","album":"大吟醸","uri":"http://192.168.1.1:8200/MediaItems/582.flac","trackType":"flac"} Feb 22 21:19:29 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:29 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:29 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"わかれうた","artist":"中島みゆき","album":"大吟醸","uri":"http://192.168.1.1:8200/MediaItems/582.flac","trackType":"flac"} Feb 22 21:19:29 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:29 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 578ms Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 577ms Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand playlistinfo took 148 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand playlistinfo took 144 milliseconds Feb 22 21:19:29 volumio volumio[960]: info: sendMpdCommand playlistinfo took 143 milliseconds Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:29 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:29 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"677 Kbps","isStreaming":false,"title":"わかれうた","artist":"中島みゆき","album":"大吟醸","uri":"http://192.168.1.1:8200/MediaItems/582.flac","trackType":"flac"} Feb 22 21:19:29 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:29 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:29 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":663,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"わかれうた","artist":"中島みゆき","album":"大吟醸","uri":"http://192.168.1.1:8200/MediaItems/582.flac","trackType":"flac"} Feb 22 21:19:29 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:29 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:29 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":663,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"わかれうた","artist":"中島みゆき","album":"大吟醸","uri":"http://192.168.1.1:8200/MediaItems/582.flac","trackType":"flac"} Feb 22 21:19:29 volumio volumio[960]: verbose: CURRENT POSITION 4 Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:29 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 655ms Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 422ms Feb 22 21:19:29 volumio volumio[960]: info: ------------------------------ 418ms Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:29 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:29 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:41 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 22 21:19:41 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:43 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 22 21:19:43 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:45 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 22 21:19:45 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$0 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$1 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$2 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$3 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$4 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$5 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$6 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$7 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$8 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$9 Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$A Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$B Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$C Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$D Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$E Feb 22 21:19:45 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$F Feb 22 21:19:45 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$0 in service upnp_browser Feb 22 21:19:45 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$1 in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$2 in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$3 in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$4 in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$5 in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$6 in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$7 in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$8 in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$9 in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$A in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$B in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$C in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$D in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$E in service upnp_browser Feb 22 21:19:46 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$F in service upnp_browser Feb 22 21:19:53 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::ClearQueue Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::stop Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::stPlaybackTimer Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::updateTrackBlock Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrackBlock Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::serviceStop Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 4 Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::serviceStop Feb 22 21:19:53 volumio volumio[960]: info: [1740226793059] ControllerUPNPBrowser::stop Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::clearPlayQueue Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::saveQueue Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushQueue Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::addQueueItems Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::addQueueItems Feb 22 21:19:53 volumio volumio[960]: info: Preload queue cleared Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$0 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$0 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$1 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$1 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$2 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$2 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$3 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$3 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$4 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$4 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$5 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$5 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$6 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$6 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$7 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$7 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$8 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$8 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$9 Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$9 Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$A Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$A Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$B Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$B Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$C Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$C Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$D Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$D Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$E Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$E Feb 22 21:19:53 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$F Feb 22 21:19:53 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$3$0$0$F Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushQueue Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::saveQueue Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::updateTrackBlock Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrackBlock Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPlay Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::play index 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::stop Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::play index undefined Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::startPlaybackTimer Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: info: [1740226793151] ControllerUPNPBrowser::clearAddPlayTrack Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand stop took 115 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand stop took 27 milliseconds Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:53 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 12 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand clear took 10 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 7 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 6 milliseconds Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.1:8200/MediaItems/852.flac" Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService stop Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: No code Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService stop Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: No code Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 127ms Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 127ms Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:53 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:53 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:53 volumio volumio[960]: error: updateQueue error: null Feb 22 21:19:53 volumio volumio[960]: error: updateQueue error: null Feb 22 21:19:53 volumio volumio[960]: error: updateQueue error: null Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 135ms Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 131 milliseconds Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.1:8200/MediaItems/852.flac" Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 12ms Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 10ms Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 152ms Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:53 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand add "http://192.168.1.1:8200/MediaItems/852.flac" took 24 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand play Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:53 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:53 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 18ms Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand play took 16 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 16ms Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 15ms Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 35 milliseconds Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:53 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 57 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 55 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 15 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 12 milliseconds Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"852.flac","artist":null,"album":null,"uri":"http://192.168.1.1:8200/MediaItems/852.flac","trackType":"flac"} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 83ms Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:53 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:19:53 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:19:53 volumio volumio[960]: info: Feb 22 21:19:53 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 38 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 43ms Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 35 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 32 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 28 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 20ms Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 16 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 15ms Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand status took 13 milliseconds Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"プレイバック part 2","artist":"山口百恵","album":"山口百恵 ベスト・コレクション VOL. 2","uri":"http://192.168.1.1:8200/MediaItems/852.flac","trackType":"flac"} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:53 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"プレイバック part 2","artist":"山口百恵","album":"山口百恵 ベスト・コレクション VOL. 2","uri":"http://192.168.1.1:8200/MediaItems/852.flac","trackType":"flac"} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:53 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"プレイバック part 2","artist":"山口百恵","album":"山口百恵 ベスト・コレクション VOL. 2","uri":"http://192.168.1.1:8200/MediaItems/852.flac","trackType":"flac"} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:53 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"876 Kbps","isStreaming":false,"title":"プレイバック part 2","artist":"山口百恵","album":"山口百恵 ベスト・コレクション VOL. 2","uri":"http://192.168.1.1:8200/MediaItems/852.flac","trackType":"flac"} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:53 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 379ms Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 378ms Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 340ms Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 338ms Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 286 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 286 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 283 milliseconds Feb 22 21:19:53 volumio volumio[960]: info: sendMpdCommand playlistinfo took 283 milliseconds Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42,"duration":204,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"889 Kbps","isStreaming":false,"title":"プレイバック part 2","artist":"山口百恵","album":"山口百恵 ベスト・コレクション VOL. 2","uri":"http://192.168.1.1:8200/MediaItems/852.flac","trackType":"flac"} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:53 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63,"duration":204,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"889 Kbps","isStreaming":false,"title":"プレイバック part 2","artist":"山口百恵","album":"山口百恵 ベスト・コレクション VOL. 2","uri":"http://192.168.1.1:8200/MediaItems/852.flac","trackType":"flac"} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:53 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126,"duration":204,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"889 Kbps","isStreaming":false,"title":"プレイバック part 2","artist":"山口百恵","album":"山口百恵 ベスト・コレクション VOL. 2","uri":"http://192.168.1.1:8200/MediaItems/852.flac","trackType":"flac"} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:53 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:19:53 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":147,"duration":204,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"889 Kbps","isStreaming":false,"title":"プレイバック part 2","artist":"山口百恵","album":"山口百恵 ベスト・コレクション VOL. 2","uri":"http://192.168.1.1:8200/MediaItems/852.flac","trackType":"flac"} Feb 22 21:19:53 volumio volumio[960]: verbose: CURRENT POSITION 5 Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:19:53 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 516ms Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 515ms Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 490ms Feb 22 21:19:53 volumio volumio[960]: info: ------------------------------ 485ms Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:53 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:53 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:53 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:19:54 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:19:54 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 22 21:20:14 volumio volumio[960]: info: Preload queue cleared Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$0 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$2 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$3 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$4 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$5 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$6 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$7 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$8 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$9 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$A Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$B Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$C Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$D Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$E Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$F Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$10 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$11 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$12 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$13 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$14 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$15 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$16 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$17 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$18 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$19 Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1A Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1B Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1C Feb 22 21:20:14 volumio volumio[960]: info: Preloading song: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1D Feb 22 21:20:14 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$0 in service upnp_browser Feb 22 21:20:14 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1 in service upnp_browser Feb 22 21:20:14 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$2 in service upnp_browser Feb 22 21:20:14 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$3 in service upnp_browser Feb 22 21:20:14 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$4 in service upnp_browser Feb 22 21:20:14 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$5 in service upnp_browser Feb 22 21:20:14 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$6 in service upnp_browser Feb 22 21:20:14 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$7 in service upnp_browser Feb 22 21:20:14 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$8 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$9 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$A in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$B in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$C in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$D in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$E in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$F in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$10 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$11 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$12 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$13 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$14 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$15 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$16 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$17 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$18 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$19 in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1A in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1B in service upnp_browser Feb 22 21:20:15 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1C in service upnp_browser Feb 22 21:20:16 volumio volumio[960]: info: Exploding uri upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1D in service upnp_browser Feb 22 21:20:22 volumio volumio[960]: info: Preload queue cleared Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::ClearQueue Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::stop Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::stPlaybackTimer Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::updateTrackBlock Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrackBlock Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::serviceStop Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 5 Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::serviceStop Feb 22 21:20:22 volumio volumio[960]: info: [1740226822645] ControllerUPNPBrowser::stop Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::clearPlayQueue Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::saveQueue Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPushQueue Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::addQueueItems Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::addQueueItems Feb 22 21:20:22 volumio volumio[960]: info: Preload queue cleared Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$0 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$0 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$2 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$2 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$3 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$3 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$4 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$4 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$5 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$5 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$6 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$6 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$7 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$7 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$8 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$8 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$9 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$9 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$A Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$A Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$B Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$B Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$C Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$C Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$D Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$D Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$E Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$E Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$F Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$F Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$10 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$10 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$11 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$11 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$12 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$12 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$13 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$13 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$14 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$14 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$15 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$15 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$16 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$16 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$17 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$17 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$18 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$18 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$19 Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$19 Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1A Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1A Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1B Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1B Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1C Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1C Feb 22 21:20:22 volumio volumio[960]: info: Adding Item to queue: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1D Feb 22 21:20:22 volumio volumio[960]: info: Using cached record of: upnp/http://192.168.1.1:8200/ctl/ContentDir@64$1$10$1D Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPushQueue Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::saveQueue Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::updateTrackBlock Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrackBlock Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPlay Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::play index 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::stop Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::play index undefined Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::startPlaybackTimer Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: info: [1740226822734] ControllerUPNPBrowser::clearAddPlayTrack Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand stop took 107 milliseconds Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand stop took 19 milliseconds Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:20:22 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand status took 11 milliseconds Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand clear took 10 milliseconds Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand status took 8 milliseconds Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand status took 6 milliseconds Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.1:8200/MediaItems/755.flac" Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 21:20:22 volumio volumio[960]: verbose: CURRENT POSITION 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::syncState stateService stop Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:22 volumio volumio[960]: info: No code Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 21:20:22 volumio volumio[960]: verbose: CURRENT POSITION 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::syncState stateService stop Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:22 volumio volumio[960]: info: No code Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:22 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:22 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:22 volumio volumio[960]: info: ------------------------------ 116ms Feb 22 21:20:22 volumio volumio[960]: info: ------------------------------ 115ms Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:20:22 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:20:22 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:20:22 volumio volumio[960]: error: updateQueue error: null Feb 22 21:20:22 volumio volumio[960]: error: updateQueue error: null Feb 22 21:20:22 volumio volumio[960]: error: updateQueue error: null Feb 22 21:20:22 volumio volumio[960]: info: ------------------------------ 124ms Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand playlistinfo took 120 milliseconds Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.1:8200/MediaItems/755.flac" Feb 22 21:20:22 volumio volumio[960]: info: ------------------------------ 12ms Feb 22 21:20:22 volumio volumio[960]: info: ------------------------------ 11ms Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:20:22 volumio volumio[960]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 22 21:20:22 volumio volumio[960]: info: ------------------------------ 141ms Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:20:22 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand add "http://192.168.1.1:8200/MediaItems/755.flac" took 21 milliseconds Feb 22 21:20:22 volumio volumio[960]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand play Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:20:22 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:20:22 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:20:22 volumio volumio[960]: info: ------------------------------ 29ms Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand play took 25 milliseconds Feb 22 21:20:22 volumio volumio[960]: info: ------------------------------ 24ms Feb 22 21:20:22 volumio volumio[960]: info: ------------------------------ 21ms Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:20:22 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand status took 38 milliseconds Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand status took 37 milliseconds Feb 22 21:20:22 volumio volumio[960]: info: sendMpdCommand status took 35 milliseconds Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:20:22 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:20:22 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:20:22 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:20:22 volumio volumio[960]: info: Feb 22 21:20:22 volumio volumio[960]: ---------------------------- MPD announces system playlist update Feb 22 21:20:22 volumio volumio[960]: info: Ignoring MPD Status Update Feb 22 21:20:23 volumio volumio[960]: info: Feb 22 21:20:23 volumio volumio[960]: ---------------------------- MPD announces state update: player Feb 22 21:20:23 volumio volumio[960]: info: ControllerMpd::getState Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Feb 22 21:20:23 volumio volumio[960]: info: ------------------------------ 30ms Feb 22 21:20:23 volumio volumio[960]: info: sendMpdCommand status took 27 milliseconds Feb 22 21:20:23 volumio volumio[960]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 22 21:20:23 volumio volumio[960]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 22 21:20:23 volumio volumio[960]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 22 21:20:23 volumio volumio[960]: info: ------------------------------ 17ms Feb 22 21:20:23 volumio volumio[960]: info: sendMpdCommand status took 15 milliseconds Feb 22 21:20:23 volumio volumio[960]: info: ------------------------------ 14ms Feb 22 21:20:23 volumio volumio[960]: info: sendMpdCommand status took 11 milliseconds Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::parseState Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 21:20:23 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:23 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"401 Kbps","isStreaming":false,"title":"卒業写真","artist":"荒井 由美","album":"Super Best of Yumi Arai (Disc2)","uri":"http://192.168.1.1:8200/MediaItems/755.flac","trackType":"flac"} Feb 22 21:20:23 volumio volumio[960]: verbose: CURRENT POSITION 7 Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Feb 22 21:20:23 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:23 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"401 Kbps","isStreaming":false,"title":"卒業写真","artist":"荒井 由美","album":"Super Best of Yumi Arai (Disc2)","uri":"http://192.168.1.1:8200/MediaItems/755.flac","trackType":"flac"} Feb 22 21:20:23 volumio volumio[960]: verbose: CURRENT POSITION 7 Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:20:23 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:23 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"401 Kbps","isStreaming":false,"title":"卒業写真","artist":"荒井 由美","album":"Super Best of Yumi Arai (Disc2)","uri":"http://192.168.1.1:8200/MediaItems/755.flac","trackType":"flac"} Feb 22 21:20:23 volumio volumio[960]: verbose: CURRENT POSITION 7 Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:20:23 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: ------------------------------ 114ms Feb 22 21:20:23 volumio volumio[960]: info: ------------------------------ 179ms Feb 22 21:20:23 volumio volumio[960]: info: ------------------------------ 179ms Feb 22 21:20:23 volumio volumio[960]: info: sendMpdCommand playlistinfo took 162 milliseconds Feb 22 21:20:23 volumio volumio[960]: info: sendMpdCommand playlistinfo took 159 milliseconds Feb 22 21:20:23 volumio volumio[960]: info: sendMpdCommand playlistinfo took 159 milliseconds Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:20:23 volumio volumio[960]: verbose: ControllerMpd::parseTrackInfo Feb 22 21:20:23 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:23 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"401 Kbps","isStreaming":false,"title":"卒業写真","artist":"荒井 由美","album":"Super Best of Yumi Arai (Disc2)","uri":"http://192.168.1.1:8200/MediaItems/755.flac","trackType":"flac"} Feb 22 21:20:23 volumio volumio[960]: verbose: CURRENT POSITION 7 Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:20:23 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:23 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"401 Kbps","isStreaming":false,"title":"卒業写真","artist":"荒井 由美","album":"Super Best of Yumi Arai (Disc2)","uri":"http://192.168.1.1:8200/MediaItems/755.flac","trackType":"flac"} Feb 22 21:20:23 volumio volumio[960]: verbose: CURRENT POSITION 7 Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:20:23 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: ControllerMpd::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::servicePushState Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getTrack 7 Feb 22 21:20:23 volumio volumio[960]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"401 Kbps","isStreaming":false,"title":"卒業写真","artist":"荒井 由美","album":"Super Best of Yumi Arai (Disc2)","uri":"http://192.168.1.1:8200/MediaItems/755.flac","trackType":"flac"} Feb 22 21:20:23 volumio volumio[960]: verbose: CURRENT POSITION 7 Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState stateService play Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus play Feb 22 21:20:23 volumio volumio[960]: info: Received an update from plugin. extracting info from payload Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::pushState Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Feb 22 21:20:23 volumio volumio[960]: info: ------------------------------ 342ms Feb 22 21:20:23 volumio volumio[960]: info: ------------------------------ 328ms Feb 22 21:20:23 volumio volumio[960]: info: ------------------------------ 325ms Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Feb 22 21:20:23 volumio volumio[960]: info: CoreStateMachine::getQueue Feb 22 21:20:23 volumio volumio[960]: info: CorePlayQueue::getQueue Feb 22 21:20:38 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 22 21:20:38 volumio volumio[960]: info: Preload queue cleared Feb 22 21:20:43 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 22 21:20:43 volumio volumio[960]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 21:20:43 volumio volumio[960]: TypeError: Cannot read property 'length' of undefined Feb 22 21:20:43 volumio volumio[960]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Feb 22 21:20:43 volumio volumio[960]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Feb 22 21:20:43 volumio volumio[960]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Feb 22 21:20:43 volumio volumio[960]: at Parser.emit (events.js:315:20) Feb 22 21:20:43 volumio volumio[960]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Feb 22 21:20:43 volumio volumio[960]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Feb 22 21:20:43 volumio volumio[960]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Feb 22 21:20:43 volumio volumio[960]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Feb 22 21:20:43 volumio volumio[960]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Feb 22 21:20:43 volumio volumio[960]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Feb 22 21:20:43 volumio volumio[960]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Feb 22 21:20:43 volumio volumio[960]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Feb 22 21:20:43 volumio volumio[960]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Feb 22 21:20:43 volumio volumio[960]: at IncomingMessage.emit (events.js:327:22) Feb 22 21:20:43 volumio volumio[960]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 22 21:20:43 volumio volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 22 21:20:43 volumio volumio[960]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 21:20:44 volumio sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-22 21:19 Feb 22 21:20:44 volumio sudo[2029]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"