-- Logs begin at Sun 2025-05-25 13:22:44 CST, end at Sun 2025-05-25 13:30:37 CST. -- May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::ClearQueue May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::addQueueItems May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::addQueueItems May 25 13:29:17 volumio volumio[910]: info: Preload queue cleared May 25 13:29:17 volumio volumio[910]: info: Adding Item to queue: albums://%3F%3F%3F%3F%3F/%3F%3F%3F%3F--%3F%3F%3F%3F%3F May 25 13:29:17 volumio volumio[910]: info: Exploding uri albums://%3F%3F%3F%3F%3F/%3F%3F%3F%3F--%3F%3F%3F%3F%3F in service mpd May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrackBlock May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPlay May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::play index 0 May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::play index undefined May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand stop took 2 milliseconds May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:29:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand clear took 6 milliseconds May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav" May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:29:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:29:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:29:17 volumio volumio[910]: error: updateQueue error: null May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:29:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 11ms May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand add "INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav" took 8 milliseconds May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 6ms May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 6ms May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:29:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:29:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:29:17 volumio volumio[910]: error: Upnp client error: Error: This socket has been ended by the other party May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 12ms May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand play took 9 milliseconds May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 8ms May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 7ms May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand status took 57 milliseconds May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand status took 62 milliseconds May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand status took 61 milliseconds May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:29:17 volumio volumio[910]: info: May 25 13:29:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand status took 25 milliseconds May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 16 milliseconds May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 15 milliseconds May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 17 milliseconds May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand status took 10 milliseconds May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"?????","album":"????--?????","uri":"INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav","trackType":"wav"} May 25 13:29:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"?????","album":"????--?????","uri":"INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav","trackType":"wav"} May 25 13:29:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:29:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"?????","album":"????--?????","uri":"INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav","trackType":"wav"} May 25 13:29:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:29:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 161ms May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 223ms May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 214ms May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand status took 138 milliseconds May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 130 milliseconds May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 126 milliseconds May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15,"duration":216,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"?????","album":"????--?????","uri":"INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav","trackType":"wav"} May 25 13:29:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:29:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26,"duration":216,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"?????","album":"????--?????","uri":"INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav","trackType":"wav"} May 25 13:29:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:29:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 322ms May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 295ms May 25 13:29:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 196 milliseconds May 25 13:29:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:29:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30,"duration":216,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"?????","album":"????--?????","uri":"INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav","trackType":"wav"} May 25 13:29:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:29:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:17 volumio volumio[910]: info: ------------------------------ 419ms May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::ClearQueue May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrackBlock May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::serviceStop May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::serviceStop May 25 13:29:55 volumio volumio[910]: info: ControllerMpd::stop May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::addQueueItems May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::addQueueItems May 25 13:29:55 volumio volumio[910]: info: Preload queue cleared May 25 13:29:55 volumio volumio[910]: info: Adding Item to queue: albums://%CD%AE%3F%3F/%D2%B0%3F%3F%CB%B5%20%5BHQCDII%5D%20%3F%3F%EF%BF%BD%EF%BF%BD%3F%3F%20 May 25 13:29:55 volumio volumio[910]: info: Using cached record of: albums://%CD%AE%3F%3F/%D2%B0%3F%3F%CB%B5%20%5BHQCDII%5D%20%3F%3F%EF%BF%BD%EF%BF%BD%3F%3F%20 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrackBlock May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPlay May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::play index 0 May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::play index undefined May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: May 25 13:29:55 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:29:55 volumio volumio[910]: info: sendMpdCommand stop took 86 milliseconds May 25 13:29:55 volumio volumio[910]: info: ControllerMpd::getState May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:29:55 volumio volumio[910]: info: May 25 13:29:55 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:29:55 volumio volumio[910]: info: ControllerMpd::getState May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:29:55 volumio volumio[910]: info: May 25 13:29:55 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:29:55 volumio volumio[910]: info: ControllerMpd::getState May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:29:55 volumio volumio[910]: info: sendMpdCommand status took 10 milliseconds May 25 13:29:55 volumio volumio[910]: info: sendMpdCommand status took 6 milliseconds May 25 13:29:55 volumio volumio[910]: info: sendMpdCommand status took 3 milliseconds May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:29:55 volumio volumio[910]: info: sendMpdCommand playlistinfo took 8 milliseconds May 25 13:29:55 volumio volumio[910]: info: sendMpdCommand playlistinfo took 9 milliseconds May 25 13:29:55 volumio volumio[910]: info: sendMpdCommand playlistinfo took 8 milliseconds May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:29:55 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:29:55 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"?????","album":"????--?????","uri":"INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav","trackType":"wav"} May 25 13:29:55 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:55 volumio volumio[910]: info: No code May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:55 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"?????","album":"????--?????","uri":"INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav","trackType":"wav"} May 25 13:29:55 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:55 volumio volumio[910]: info: No code May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:55 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"?????","album":"????--?????","uri":"INTERNAL/刘紫玲 -疯潮.红色摇滚.2010/01 南泥湾.wav","trackType":"wav"} May 25 13:29:55 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:29:55 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:55 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:55 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:56 volumio volumio[910]: info: No code May 25 13:29:56 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:29:56 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:29:56 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:29:56 volumio volumio[910]: info: ------------------------------ 149ms May 25 13:29:56 volumio volumio[910]: info: ------------------------------ 145ms May 25 13:29:56 volumio volumio[910]: info: ------------------------------ 142ms May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::ClearQueue May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::addQueueItems May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::addQueueItems May 25 13:30:06 volumio volumio[910]: info: Preload queue cleared May 25 13:30:06 volumio volumio[910]: info: Adding Item to queue: albums://%3F%3F%20%20/%3F%3F--%3F%3F%3F%3F%3F May 25 13:30:06 volumio volumio[910]: info: Exploding uri albums://%3F%3F%20%20/%3F%3F--%3F%3F%3F%3F%3F in service mpd May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrackBlock May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioPlay May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::play index 0 May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::play index undefined May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/好薇 赞歌（1：1母盘直刻版）/01.唱支山歌给党听.wav May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand stop took 2 milliseconds May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:06 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand clear took 4 milliseconds May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/好薇 赞歌（1：1母盘直刻版）/01.唱支山歌给党听.wav" May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:06 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:06 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:06 volumio volumio[910]: error: updateQueue error: null May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:06 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:06 volumio volumio[910]: info: ------------------------------ 7ms May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand add "INTERNAL/好薇 赞歌（1：1母盘直刻版）/01.唱支山歌给党听.wav" took 6 milliseconds May 25 13:30:06 volumio volumio[910]: info: ------------------------------ 5ms May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:06 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:06 volumio volumio[910]: info: ------------------------------ 8ms May 25 13:30:06 volumio volumio[910]: info: ------------------------------ 15ms May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand play took 13 milliseconds May 25 13:30:06 volumio volumio[910]: info: ------------------------------ 12ms May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:06 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:06 volumio volumio[910]: info: ------------------------------ 9ms May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand status took 29 milliseconds May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand status took 30 milliseconds May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand status took 21 milliseconds May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand status took 38 milliseconds May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:06 volumio volumio[910]: info: May 25 13:30:06 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand playlistinfo took 33 milliseconds May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand playlistinfo took 34 milliseconds May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand playlistinfo took 35 milliseconds May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand playlistinfo took 36 milliseconds May 25 13:30:06 volumio volumio[910]: info: sendMpdCommand status took 22 milliseconds May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":238,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"?? ","album":"??--?????","uri":"INTERNAL/好薇 赞歌（1：1母盘直刻版）/01.唱支山歌给党听.wav","trackType":"wav"} May 25 13:30:06 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":238,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"?? ","album":"??--?????","uri":"INTERNAL/好薇 赞歌（1：1母盘直刻版）/01.唱支山歌给党听.wav","trackType":"wav"} May 25 13:30:06 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:06 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":238,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"?? ","album":"??--?????","uri":"INTERNAL/好薇 赞歌（1：1母盘直刻版）/01.唱支山歌给党听.wav","trackType":"wav"} May 25 13:30:06 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:06 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:06 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":238,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"?? ","album":"??--?????","uri":"INTERNAL/好薇 赞歌（1：1母盘直刻版）/01.唱支山歌给党听.wav","trackType":"wav"} May 25 13:30:06 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:06 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:06 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:06 volumio volumio[910]: info: ------------------------------ 182ms May 25 13:30:07 volumio volumio[910]: info: ------------------------------ 408ms May 25 13:30:07 volumio volumio[910]: info: ------------------------------ 402ms May 25 13:30:07 volumio volumio[910]: info: ------------------------------ 402ms May 25 13:30:07 volumio volumio[910]: info: sendMpdCommand playlistinfo took 318 milliseconds May 25 13:30:07 volumio volumio[910]: info: sendMpdCommand playlistinfo took 320 milliseconds May 25 13:30:07 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:07 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:07 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:07 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":273,"duration":238,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"?? ","album":"??--?????","uri":"INTERNAL/好薇 赞歌（1：1母盘直刻版）/01.唱支山歌给党听.wav","trackType":"wav"} May 25 13:30:07 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:07 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:07 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:07 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:07 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:07 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:07 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:07 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:07 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":273,"duration":238,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"?? ","album":"??--?????","uri":"INTERNAL/好薇 赞歌（1：1母盘直刻版）/01.唱支山歌给党听.wav","trackType":"wav"} May 25 13:30:07 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:07 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:07 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:07 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:07 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:07 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:07 volumio volumio[910]: info: ------------------------------ 499ms May 25 13:30:07 volumio volumio[910]: info: ------------------------------ 499ms May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::ClearQueue May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrackBlock May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::serviceStop May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::serviceStop May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::stop May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::addQueueItems May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::addQueueItems May 25 13:30:17 volumio volumio[910]: info: Preload queue cleared May 25 13:30:17 volumio volumio[910]: info: Adding Item to queue: albums://%3F%3F/%3F%3F%3F%3F%3F%3F-%3F%3F%3F%3F%3F May 25 13:30:17 volumio volumio[910]: info: Exploding uri albums://%3F%3F/%3F%3F%3F%3F%3F%3F-%3F%3F%3F%3F%3F in service mpd May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrackBlock May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPlay May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::play index 0 May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::play index undefined May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/孟庭苇 - 谁的眼泪在飞 1993/01 誰的眼淚在飛.wav May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand stop took 87 milliseconds May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand stop took 5 milliseconds May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand status took 8 milliseconds May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand clear took 6 milliseconds May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand status took 4 milliseconds May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/孟庭苇 - 谁的眼泪在飞 1993/01 誰的眼淚在飛.wav" May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: 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} May 25 13:30:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: No code May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 52ms May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:17 volumio volumio[910]: error: updateQueue error: null May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand status took 57 milliseconds May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 56ms May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 54 milliseconds May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand add "INTERNAL/孟庭苇 - 谁的眼泪在飞 1993/01 誰的眼淚在飛.wav" took 53 milliseconds May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 6ms May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: 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} May 25 13:30:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: No code May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 135ms May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 140ms May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 117ms May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand play took 112 milliseconds May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 33ms May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 26ms May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:17 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:17 volumio volumio[910]: info: May 25 13:30:17 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand status took 56 milliseconds May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand status took 49 milliseconds May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 48ms May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand status took 43 milliseconds May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 70 milliseconds May 25 13:30:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 75 milliseconds May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":322,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??????","artist":"??","album":"??????-?????","uri":"INTERNAL/孟庭苇 - 谁的眼泪在飞 1993/01 誰的眼淚在飛.wav","trackType":"wav"} May 25 13:30:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":322,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??????","artist":"??","album":"??????-?????","uri":"INTERNAL/孟庭苇 - 谁的眼泪在飞 1993/01 誰的眼淚在飛.wav","trackType":"wav"} May 25 13:30:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":322,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??????","artist":"??","album":"??????-?????","uri":"INTERNAL/孟庭苇 - 谁的眼泪在飞 1993/01 誰的眼淚在飛.wav","trackType":"wav"} May 25 13:30:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":322,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??????","artist":"??","album":"??????-?????","uri":"INTERNAL/孟庭苇 - 谁的眼泪在飞 1993/01 誰的眼淚在飛.wav","trackType":"wav"} May 25 13:30:17 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 215ms May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 458ms May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 449ms May 25 13:30:17 volumio volumio[910]: info: ------------------------------ 450ms May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::ClearQueue May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrackBlock May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::serviceStop May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::serviceStop May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::stop May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::addQueueItems May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::addQueueItems May 25 13:30:25 volumio volumio[910]: info: Preload queue cleared May 25 13:30:25 volumio volumio[910]: info: Adding Item to queue: albums://24K%3F%3F%3F%3F1%3A1%3F%3FCD/%3F%3F-%3F%3F%3F%3F%3F May 25 13:30:25 volumio volumio[910]: info: Exploding uri albums://24K%3F%3F%3F%3F1%3A1%3F%3FCD/%3F%3F-%3F%3F%3F%3F%3F in service mpd May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::saveQueue May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrackBlock May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPlay May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::play index 0 May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::stop May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::play index undefined May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/李梦瑶 唯一(24K黄金母盘1：1直刻版）/01.一場遊戲一場夢.wav May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand stop took 53 milliseconds May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand stop took 10 milliseconds May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:25 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand status took 13 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand clear took 11 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand status took 7 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand status took 5 milliseconds May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/李梦瑶 唯一(24K黄金母盘1：1直刻版）/01.一場遊戲一場夢.wav" May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: 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} May 25 13:30:25 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: No code May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: 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} May 25 13:30:25 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: No code May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 102ms May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 100ms May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:25 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:25 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:25 volumio volumio[910]: error: updateQueue error: null May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:25 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 109ms May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand playlistinfo took 107 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand add "INTERNAL/李梦瑶 唯一(24K黄金母盘1：1直刻版）/01.一場遊戲一場夢.wav" took 108 milliseconds May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 13ms May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 10ms May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play May 25 13:30:25 volumio volumio[910]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 130ms May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:25 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces system playlist update May 25 13:30:25 volumio volumio[910]: info: Ignoring MPD Status Update May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 30ms May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand play took 29 milliseconds May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 26ms May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 24ms May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand status took 32 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand status took 33 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand status took 24 milliseconds May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:25 volumio volumio[910]: info: May 25 13:30:25 volumio volumio[910]: ---------------------------- MPD announces state update: player May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::getState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand status took 38 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand playlistinfo took 38 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand playlistinfo took 39 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand playlistinfo took 38 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand status took 25 milliseconds May 25 13:30:25 volumio volumio[910]: info: sendMpdCommand status took 21 milliseconds May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::parseState May 25 13:30:25 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"24K????1:1??CD","album":"??-?????","uri":"INTERNAL/李梦瑶 唯一(24K黄金母盘1：1直刻版）/01.一場遊戲一場夢.wav","trackType":"wav"} May 25 13:30:25 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"24K????1:1??CD","album":"??-?????","uri":"INTERNAL/李梦瑶 唯一(24K黄金母盘1：1直刻版）/01.一場遊戲一場夢.wav","trackType":"wav"} May 25 13:30:25 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:25 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":269,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"24K????1:1??CD","album":"??-?????","uri":"INTERNAL/李梦瑶 唯一(24K黄金母盘1：1直刻版）/01.一場遊戲一場夢.wav","trackType":"wav"} May 25 13:30:25 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:25 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:25 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:25 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:25 volumio volumio[910]: info: ------------------------------ 149ms May 25 13:30:26 volumio volumio[910]: info: ------------------------------ 220ms May 25 13:30:26 volumio volumio[910]: info: ------------------------------ 211ms May 25 13:30:26 volumio volumio[910]: info: sendMpdCommand playlistinfo took 151 milliseconds May 25 13:30:26 volumio volumio[910]: info: sendMpdCommand playlistinfo took 151 milliseconds May 25 13:30:26 volumio volumio[910]: info: sendMpdCommand playlistinfo took 151 milliseconds May 25 13:30:26 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:26 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:26 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo May 25 13:30:26 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:26 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:26 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":269,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"24K????1:1??CD","album":"??-?????","uri":"INTERNAL/李梦瑶 唯一(24K黄金母盘1：1直刻版）/01.一場遊戲一場夢.wav","trackType":"wav"} May 25 13:30:26 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:26 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:26 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:26 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:26 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:26 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:26 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":269,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"24K????1:1??CD","album":"??-?????","uri":"INTERNAL/李梦瑶 唯一(24K黄金母盘1：1直刻版）/01.一場遊戲一場夢.wav","trackType":"wav"} May 25 13:30:26 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:26 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:26 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:26 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:26 volumio volumio[910]: info: ControllerMpd::pushState May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::servicePushState May 25 13:30:26 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:26 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":269,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???????","artist":"24K????1:1??CD","album":"??-?????","uri":"INTERNAL/李梦瑶 唯一(24K黄金母盘1：1直刻版）/01.一場遊戲一場夢.wav","trackType":"wav"} May 25 13:30:26 volumio volumio[910]: verbose: CURRENT POSITION 0 May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::syncState stateService play May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play May 25 13:30:26 volumio volumio[910]: info: Received an update from plugin. extracting info from payload May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:26 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:26 volumio volumio[910]: info: CoreStateMachine::pushState May 25 13:30:26 volumio volumio[910]: info: CorePlayQueue::getTrack 0 May 25 13:30:26 volumio volumio[910]: info: CoreCommandRouter::volumioPushState May 25 13:30:26 volumio volumio[910]: info: ------------------------------ 587ms May 25 13:30:26 volumio volumio[910]: info: ------------------------------ 574ms May 25 13:30:26 volumio volumio[910]: info: ------------------------------ 564ms May 25 13:30:36 volumio volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 13:30:36 volumio volumio[910]: Error: connect ETIMEDOUT 199.59.148.246:80 May 25 13:30:36 volumio volumio[910]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 25 13:30:36 volumio volumio[910]: errno: -110, May 25 13:30:36 volumio volumio[910]: code: 'ETIMEDOUT', May 25 13:30:36 volumio volumio[910]: syscall: 'connect', May 25 13:30:36 volumio volumio[910]: address: '199.59.148.246', May 25 13:30:36 volumio volumio[910]: port: 80 May 25 13:30:36 volumio volumio[910]: } May 25 13:30:36 volumio volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 13:30:37 volumio sudo[3069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 13:29 May 25 13:30:37 volumio sudo[3069]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"