-- Logs begin at Tue 2025-05-06 12:37:18 CST, end at Tue 2025-05-06 12:47:45 CST. -- May 06 12:46:00 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::play index 9 May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::stop May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:00 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:00 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:00 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:00 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:00 volumio volumio[3130]: info: sendMpdCommand stop took 2 milliseconds May 06 12:46:00 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:00 volumio volumio[3130]: info: May 06 12:46:00 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:00 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:00 volumio volumio[3130]: info: sendMpdCommand clear took 3 milliseconds May 06 12:46:00 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:00 volumio volumio[3130]: info: May 06 12:46:00 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:00 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:00 volumio volumio[3130]: info: May 06 12:46:00 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:00 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:00 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:00 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:00 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:00 volumio volumio[3130]: info: ------------------------------ 13ms May 06 12:46:00 volumio volumio[3130]: info: ------------------------------ 9ms May 06 12:46:00 volumio volumio[3130]: info: ------------------------------ 7ms May 06 12:46:00 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::play index 9 May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::stop May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:00 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:00 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:00 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:00 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:00 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:00 volumio volumio[3130]: info: sendMpdCommand stop took 2 milliseconds May 06 12:46:00 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:00 volumio volumio[3130]: info: May 06 12:46:00 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:00 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:00 volumio volumio[3130]: info: sendMpdCommand clear took 3 milliseconds May 06 12:46:00 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:00 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:00 volumio volumio[3130]: info: ------------------------------ 6ms May 06 12:46:00 volumio volumio[3130]: info: May 06 12:46:00 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:00 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:00 volumio volumio[3130]: info: May 06 12:46:00 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:00 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:00 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:00 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:00 volumio volumio[3130]: info: ------------------------------ 7ms May 06 12:46:00 volumio volumio[3130]: info: ------------------------------ 6ms May 06 12:46:02 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:02 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:02 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:02 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:02 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:02 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:02 volumio volumio[3130]: info: sendMpdCommand stop took 2 milliseconds May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:02 volumio volumio[3130]: info: May 06 12:46:02 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:02 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:02 volumio volumio[3130]: info: sendMpdCommand clear took 3 milliseconds May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:02 volumio volumio[3130]: info: May 06 12:46:02 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:02 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:02 volumio volumio[3130]: info: May 06 12:46:02 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:02 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:02 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:02 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:02 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:02 volumio volumio[3130]: info: ------------------------------ 14ms May 06 12:46:02 volumio volumio[3130]: info: ------------------------------ 10ms May 06 12:46:02 volumio volumio[3130]: info: ------------------------------ 7ms May 06 12:46:02 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:02 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:02 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:02 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:02 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:02 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:02 volumio volumio[3130]: info: sendMpdCommand stop took 2 milliseconds May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:02 volumio volumio[3130]: info: May 06 12:46:02 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:02 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:02 volumio volumio[3130]: info: sendMpdCommand clear took 3 milliseconds May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:02 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:02 volumio volumio[3130]: info: ------------------------------ 6ms May 06 12:46:02 volumio volumio[3130]: info: May 06 12:46:02 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:02 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:02 volumio volumio[3130]: info: May 06 12:46:02 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:02 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:02 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:02 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:02 volumio volumio[3130]: info: ------------------------------ 7ms May 06 12:46:02 volumio volumio[3130]: info: ------------------------------ 5ms May 06 12:46:02 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:02 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:02 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:02 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:02 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:02 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:02 volumio volumio[3130]: info: sendMpdCommand stop took 2 milliseconds May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:02 volumio volumio[3130]: info: May 06 12:46:02 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:02 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:02 volumio volumio[3130]: info: sendMpdCommand clear took 3 milliseconds May 06 12:46:02 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:02 volumio volumio[3130]: info: May 06 12:46:02 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:02 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:02 volumio volumio[3130]: info: May 06 12:46:02 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:02 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:02 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:02 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:02 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:02 volumio volumio[3130]: info: ------------------------------ 13ms May 06 12:46:02 volumio volumio[3130]: info: ------------------------------ 9ms May 06 12:46:02 volumio volumio[3130]: info: ------------------------------ 7ms May 06 12:46:03 volumio volumio[3130]: info: CoreCommandRouter::volumioSeek May 06 12:46:03 volumio volumio[3130]: info: CoreStateMachine::seek May 06 12:46:03 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:03 volumio volumio[3130]: info: TRACKBLOCK {"uri":"mnt/USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3","service":"mpd","name":"その先の出口","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=638&web=//extralarge&path=%2FUSB%2F5E84-A769&metadata=false","duration":245,"trackType":"mp3"} May 06 12:46:03 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:03 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:03 volumio volumio[3130]: info: ControllerMpd::seek May 06 12:46:03 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:03 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:03 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:03 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:03 volumio volumio[3130]: error: [2@0] {seek} Bad song index May 06 12:46:04 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:46:04 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:46:04 volumio volumio[3130]: info: Discovery: Getting this device information May 06 12:46:04 volumio volumio[3130]: info: CoreCommandRouter::volumioGetState May 06 12:46:04 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:04 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:46:05 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:05 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:05 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:05 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:05 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:05 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:05 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:05 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:05 volumio volumio[3130]: info: sendMpdCommand stop took 2 milliseconds May 06 12:46:05 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:05 volumio volumio[3130]: info: sendMpdCommand clear took 2 milliseconds May 06 12:46:05 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:05 volumio volumio[3130]: info: May 06 12:46:05 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:05 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:05 volumio volumio[3130]: info: May 06 12:46:05 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:05 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:05 volumio volumio[3130]: info: May 06 12:46:05 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:05 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:05 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:05 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:05 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:05 volumio volumio[3130]: info: ------------------------------ 12ms May 06 12:46:05 volumio volumio[3130]: info: ------------------------------ 9ms May 06 12:46:05 volumio volumio[3130]: info: ------------------------------ 7ms May 06 12:46:06 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:06 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:06 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:06 volumio volumio[3130]: info: sendMpdCommand stop took 2 milliseconds May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: info: sendMpdCommand clear took 3 milliseconds May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 13ms May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 9ms May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 7ms May 06 12:46:06 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:06 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:06 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:06 volumio volumio[3130]: info: sendMpdCommand stop took 1 milliseconds May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: info: sendMpdCommand clear took 1 milliseconds May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 5ms May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:46:06 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:06 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:06 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:06 volumio volumio[3130]: info: sendMpdCommand stop took 1 milliseconds May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: info: sendMpdCommand clear took 3 milliseconds May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 13ms May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 9ms May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 7ms May 06 12:46:06 volumio volumio[3130]: info: CoreCommandRouter::volumioPlay May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::play index undefined May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:46:06 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:06 volumio volumio[3130]: info: CoreStateMachine::startPlaybackTimer May 06 12:46:06 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::clearAddPlayTracks USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3 May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand stop May 06 12:46:06 volumio volumio[3130]: info: sendMpdCommand stop took 2 milliseconds May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand clear May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: info: sendMpdCommand clear took 3 milliseconds May 06 12:46:06 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand add "USB/5E84-A769/乃木坂46 - その先の出口 [mqms2].mp3" May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 6ms May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: info: May 06 12:46:06 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:46:06 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: error: updateQueue error: null May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 8ms May 06 12:46:06 volumio volumio[3130]: info: ------------------------------ 6ms May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 12:46:17 volumio volumio[3130]: info: Retrieving Cloud Streaming UI May 06 12:46:17 volumio volumio[3130]: info: Getting Tidal Cloud Configuration May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 12:46:17 volumio volumio[3130]: info: Getting Qobuz Cloud Configuration May 06 12:46:17 volumio volumio[3130]: info: Asking plugin for UI Config May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 12:46:17 volumio volumio[3130]: info: Getting Spotify Cloud Configuration May 06 12:46:17 volumio volumio[3130]: info: Asking plugin for UI Config May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 12:46:17 volumio volumio[3130]: info: Saving Spotify Acccount May 06 12:46:17 volumio volumio[3130]: info: Got Tidal Cloud Configuration May 06 12:46:17 volumio volumio[3130]: info: Got it May 06 12:46:17 volumio volumio[3130]: info: Got it May 06 12:46:17 volumio volumio[3130]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 12:46:17 volumio volumio[3130]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::volumioGetBrowseSources May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::volumioGetBrowseSources May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:17 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand update May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: info: May 06 12:46:19 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand update took 4 milliseconds May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: info: May 06 12:46:19 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: info: May 06 12:46:19 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: info: May 06 12:46:19 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 13 milliseconds May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 13 milliseconds May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 12 milliseconds May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 11 milliseconds May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 10 milliseconds May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 8 milliseconds May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: info: Command Router : Notfying DB Updatetrue May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: 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 06 12:46:19 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: No code May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: 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 06 12:46:19 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: No code May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: 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 06 12:46:19 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: No code May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: ------------------------------ 252ms May 06 12:46:19 volumio volumio[3130]: info: ------------------------------ 250ms May 06 12:46:19 volumio volumio[3130]: info: ------------------------------ 248ms May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: info: May 06 12:46:19 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: info: May 06 12:46:19 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 265 milliseconds May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 264 milliseconds May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 26 milliseconds May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 23 milliseconds May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 22 milliseconds May 06 12:46:19 volumio volumio[3130]: info: sendMpdCommand status took 20 milliseconds May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:19 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: 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 06 12:46:19 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: No code May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: 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 06 12:46:19 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: No code May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: 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 06 12:46:19 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: No code May 06 12:46:19 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:19 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:19 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:19 volumio volumio[3130]: info: ------------------------------ 480ms May 06 12:46:19 volumio volumio[3130]: info: ------------------------------ 234ms May 06 12:46:19 volumio volumio[3130]: info: ------------------------------ 230ms May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand update May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: info: May 06 12:46:20 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand update took 4 milliseconds May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: info: May 06 12:46:20 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 4 milliseconds May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 3 milliseconds May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: 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 06 12:46:20 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: No code May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: ------------------------------ 136ms May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: info: May 06 12:46:20 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: info: May 06 12:46:20 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 139 milliseconds May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 138 milliseconds May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 6 milliseconds May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 4 milliseconds May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 3 milliseconds May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 3 milliseconds May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: 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 06 12:46:20 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: No code May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: 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 06 12:46:20 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: No code May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: 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 06 12:46:20 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: No code May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: ------------------------------ 345ms May 06 12:46:20 volumio volumio[3130]: info: ------------------------------ 212ms May 06 12:46:20 volumio volumio[3130]: info: ------------------------------ 210ms May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: info: May 06 12:46:20 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: info: May 06 12:46:20 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 18 milliseconds May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 17 milliseconds May 06 12:46:20 volumio volumio[3130]: info: sendMpdCommand status took 16 milliseconds May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:20 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: 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 06 12:46:20 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: No code May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: 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 06 12:46:20 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: No code May 06 12:46:20 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:20 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:20 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:20 volumio volumio[3130]: info: ------------------------------ 183ms May 06 12:46:20 volumio volumio[3130]: info: ------------------------------ 182ms May 06 12:46:21 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:22 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand update May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:22 volumio volumio[3130]: info: May 06 12:46:22 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:22 volumio volumio[3130]: info: sendMpdCommand update took 5 milliseconds May 06 12:46:22 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:22 volumio volumio[3130]: info: May 06 12:46:22 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:22 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:22 volumio volumio[3130]: info: May 06 12:46:22 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:22 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:22 volumio volumio[3130]: info: May 06 12:46:22 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:22 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:22 volumio volumio[3130]: info: sendMpdCommand status took 12 milliseconds May 06 12:46:22 volumio volumio[3130]: info: sendMpdCommand status took 11 milliseconds May 06 12:46:22 volumio volumio[3130]: info: sendMpdCommand status took 10 milliseconds May 06 12:46:22 volumio volumio[3130]: info: sendMpdCommand status took 9 milliseconds May 06 12:46:22 volumio volumio[3130]: info: sendMpdCommand status took 8 milliseconds May 06 12:46:22 volumio volumio[3130]: info: sendMpdCommand status took 8 milliseconds May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:22 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:22 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:22 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:22 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:22 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: 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 06 12:46:23 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: No code May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: 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 06 12:46:23 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: No code May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: 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 06 12:46:23 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: No code May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: ------------------------------ 242ms May 06 12:46:23 volumio volumio[3130]: info: ------------------------------ 240ms May 06 12:46:23 volumio volumio[3130]: info: ------------------------------ 238ms May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: May 06 12:46:23 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: May 06 12:46:23 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 240 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 238 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 5 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 4 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 3 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 2 milliseconds May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: 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 06 12:46:23 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: No code May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: 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 06 12:46:23 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: No code May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: 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 06 12:46:23 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: No code May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: ------------------------------ 428ms May 06 12:46:23 volumio volumio[3130]: info: ------------------------------ 193ms May 06 12:46:23 volumio volumio[3130]: info: ------------------------------ 192ms May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand update May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: May 06 12:46:23 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand update took 2 milliseconds May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: May 06 12:46:23 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: May 06 12:46:23 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: May 06 12:46:23 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 8 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 7 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 6 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 6 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 4 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 3 milliseconds May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: 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 06 12:46:23 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: No code May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: 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 06 12:46:23 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: No code May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: 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 06 12:46:23 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: No code May 06 12:46:23 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:23 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:23 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:23 volumio volumio[3130]: info: ------------------------------ 218ms May 06 12:46:23 volumio volumio[3130]: info: ------------------------------ 217ms May 06 12:46:23 volumio volumio[3130]: info: ------------------------------ 215ms May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: May 06 12:46:23 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: May 06 12:46:23 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:23 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 220 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 220 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 6 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 5 milliseconds May 06 12:46:23 volumio volumio[3130]: info: sendMpdCommand status took 4 milliseconds May 06 12:46:23 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: 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 06 12:46:24 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: No code May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: 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 06 12:46:24 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: No code May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: ------------------------------ 398ms May 06 12:46:24 volumio volumio[3130]: info: ------------------------------ 184ms May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 206 milliseconds May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: 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 06 12:46:24 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: No code May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: ------------------------------ 242ms May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand rescan May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: info: May 06 12:46:24 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand rescan took 3 milliseconds May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: info: May 06 12:46:24 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 4 milliseconds May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 2 milliseconds May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: 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 06 12:46:24 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: No code May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: ------------------------------ 95ms May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: info: May 06 12:46:24 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: info: May 06 12:46:24 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 101 milliseconds May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 101 milliseconds May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 8 milliseconds May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 7 milliseconds May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 7 milliseconds May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 6 milliseconds May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: 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 06 12:46:24 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: No code May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: 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 06 12:46:24 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: No code May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: 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 06 12:46:24 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: No code May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: ------------------------------ 319ms May 06 12:46:24 volumio volumio[3130]: info: ------------------------------ 226ms May 06 12:46:24 volumio volumio[3130]: info: ------------------------------ 225ms May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: info: May 06 12:46:24 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: info: May 06 12:46:24 volumio volumio[3130]: ---------------------------- MPD announces state update: update May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 22 milliseconds May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 22 milliseconds May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 21 milliseconds May 06 12:46:24 volumio volumio[3130]: info: sendMpdCommand status took 20 milliseconds May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: Command Router : Notfying DB Updatefalse May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::Close All Modals sent May 06 12:46:24 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: 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 06 12:46:24 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: No code May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: 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 06 12:46:24 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState stateService stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: No code May 06 12:46:24 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:46:24 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:24 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:46:24 volumio volumio[3130]: info: ------------------------------ 186ms May 06 12:46:24 volumio volumio[3130]: info: ------------------------------ 185ms May 06 12:46:25 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:29 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:33 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:34 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:46:34 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:46:34 volumio volumio[3130]: info: Discovery: Getting this device information May 06 12:46:34 volumio volumio[3130]: info: CoreCommandRouter::volumioGetState May 06 12:46:34 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:46:34 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:46:37 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:41 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:45 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:50 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:52 volumio volumio[3130]: info: Disabling MyMusic plugin upnp May 06 12:46:52 volumio sudo[3903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 06 12:46:52 volumio sudo[3903]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:46:52 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... May 06 12:46:52 volumio systemd[1]: upmpdcli.service: Succeeded. May 06 12:46:52 volumio systemd[1]: Stopped UPnP Renderer front-end to MPD. May 06 12:46:52 volumio sudo[3903]: pam_unix(sudo:session): session closed for user root May 06 12:46:52 volumio volumio[3130]: info: Disabling plugin upnp May 06 12:46:52 volumio volumio[3130]: info: Done. May 06 12:46:53 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:46:56 volumio volumio[3130]: info: Enabling MyMusic plugin upnp May 06 12:46:56 volumio volumio[3130]: info: Enabling plugin upnp May 06 12:46:56 volumio volumio[3130]: info: Loading plugin "upnp"... May 06 12:46:56 volumio volumio[3130]: info: [1746506816348] Starting Upmpd Daemon May 06 12:46:56 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 12:46:56 volumio volumio[3130]: info: Done. May 06 12:46:57 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:47:01 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:47:02 volumio volumio[3130]: info: Disabling MyMusic plugin upnp_browser May 06 12:47:02 volumio volumio[3130]: info: CoreCommandRouter::volumioRemoveToBrowseSources媒体服务器 May 06 12:47:02 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 12:47:02 volumio volumio[3130]: info: Disabling plugin upnp_browser May 06 12:47:02 volumio volumio[3130]: info: Done. May 06 12:47:04 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 12:47:04 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 12:47:04 volumio volumio[3130]: info: Discovery: Getting this device information May 06 12:47:04 volumio volumio[3130]: info: CoreCommandRouter::volumioGetState May 06 12:47:04 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:47:04 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 12:47:04 volumio volumio[3130]: info: Enabling MyMusic plugin upnp_browser May 06 12:47:04 volumio volumio[3130]: info: Enabling plugin upnp_browser May 06 12:47:04 volumio volumio[3130]: info: Loading plugin "upnp_browser"... May 06 12:47:04 volumio volumio[3130]: info: PLUGIN START: upnp_browser May 06 12:47:04 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 12:47:04 volumio volumio[3130]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 12:47:04 volumio volumio[3130]: info: [1746506824957] CoreMusicLibrary::Adding element 媒体服务器 May 06 12:47:04 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 12:47:05 volumio volumio[3130]: info: Done. May 06 12:47:05 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 12:47:06 volumio sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 12:47:06 volumio sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:47:06 volumio sudo[3921]: pam_unix(sudo:session): session closed for user root May 06 12:47:06 volumio sudo[3928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 12:47:06 volumio sudo[3928]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:47:06 volumio sudo[3928]: pam_unix(sudo:session): session closed for user root May 06 12:47:06 volumio sudo[3932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 06 12:47:06 volumio sudo[3932]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 12:47:06 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 06 12:47:06 volumio sudo[3932]: pam_unix(sudo:session): session closed for user root May 06 12:47:06 volumio volumio[3130]: info: Upmpdcli Daemon Started May 06 12:47:06 volumio volumio[3934]: writing RSA key May 06 12:47:11 volumio volumio[3130]: info: Clearing queue after UPNP request May 06 12:47:11 volumio volumio[3130]: info: CoreStateMachine::ClearQueue May 06 12:47:11 volumio volumio[3130]: info: CoreStateMachine::stop May 06 12:47:11 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 12:47:11 volumio volumio[3130]: info: CorePlayQueue::clearPlayQueue May 06 12:47:11 volumio volumio[3130]: info: CorePlayQueue::saveQueue May 06 12:47:11 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:11 volumio volumio[3130]: info: CoreCommandRouter::volumioPushQueue May 06 12:47:11 volumio volumio[3130]: info: May 06 12:47:11 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:11 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:11 volumio volumio[3130]: info: May 06 12:47:11 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:11 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:11 volumio volumio[3130]: info: May 06 12:47:11 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:11 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:11 volumio volumio[3130]: error: updateQueue error: null May 06 12:47:11 volumio volumio[3130]: error: updateQueue error: null May 06 12:47:11 volumio volumio[3130]: info: ------------------------------ 7ms May 06 12:47:11 volumio volumio[3130]: info: ------------------------------ 5ms May 06 12:47:11 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:47:11 volumio volumio[3130]: info: Starting UPNP Playback May 06 12:47:11 volumio volumio[3130]: info: Preparing playback through UPNP May 06 12:47:11 volumio volumio[3130]: info: CoreCommandRouter::volumioGetState May 06 12:47:11 volumio volumio[3130]: info: CorePlayQueue::getTrack 9 May 06 12:47:11 volumio volumio[3130]: info: CoreStateMachine::setConsumeUpdateService mpd May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 4ms May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 5ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 4ms May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 2ms May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 2ms May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 2ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 2ms May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 2ms May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: May 06 12:47:12 volumio volumio[3130]: ---------------------------- MPD announces system playlist update May 06 12:47:12 volumio volumio[3130]: info: Ignoring MPD Status Update May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 3ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 2ms May 06 12:47:12 volumio volumio[3130]: info: ------------------------------ 2ms May 06 12:47:13 volumio volumio[3130]: info: May 06 12:47:13 volumio volumio[3130]: ---------------------------- MPD announces state update: player May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:47:13 volumio volumio[3130]: info: May 06 12:47:13 volumio volumio[3130]: ---------------------------- MPD announces state update: player May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:47:13 volumio volumio[3130]: info: May 06 12:47:13 volumio volumio[3130]: ---------------------------- MPD announces state update: player May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:47:13 volumio volumio[3130]: info: May 06 12:47:13 volumio volumio[3130]: ---------------------------- MPD announces state update: player May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:47:13 volumio volumio[3130]: info: sendMpdCommand status took 11 milliseconds May 06 12:47:13 volumio volumio[3130]: info: sendMpdCommand status took 8 milliseconds May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:47:13 volumio volumio[3130]: info: May 06 12:47:13 volumio volumio[3130]: ---------------------------- MPD announces state update: player May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:47:13 volumio volumio[3130]: info: May 06 12:47:13 volumio volumio[3130]: ---------------------------- MPD announces state update: player May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::getState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand status May 06 12:47:13 volumio volumio[3130]: info: sendMpdCommand status took 23 milliseconds May 06 12:47:13 volumio volumio[3130]: info: sendMpdCommand status took 20 milliseconds May 06 12:47:13 volumio volumio[3130]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 12:47:13 volumio volumio[3130]: info: sendMpdCommand playlistinfo took 5 milliseconds May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseTrackInfo May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseTrackInfo May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:47:13 volumio volumio[3130]: verbose: In UPNP mode May 06 12:47:13 volumio volumio[3130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Honey","artist":"王心凌","album":"My! Cyndi!","uri":"http://m801.music.126.net/20250506131211/bc58df7a7f19eb28f252424b9ac79312/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28017624820/62c5/09fd/00df/fc0341c303024bfe639037768ebbed15.mp3?vuutv=2JIqTJsdoCYOmPKEKS2zkg/MmME4olPQlzZPk567RzZ+HusbXlpgQN95zp6o3GWyz+8/kvQ2EKGYGhavVRlv2IqKX5Mo45HJULCjhGk04bU=","trackType":""} May 06 12:47:13 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::syncState stateService play May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus stop May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:47:13 volumio volumio[3130]: verbose: In UPNP mode May 06 12:47:13 volumio volumio[3130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Honey","artist":"王心凌","album":"My! Cyndi!","uri":"http://m801.music.126.net/20250506131211/bc58df7a7f19eb28f252424b9ac79312/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28017624820/62c5/09fd/00df/fc0341c303024bfe639037768ebbed15.mp3?vuutv=2JIqTJsdoCYOmPKEKS2zkg/MmME4olPQlzZPk567RzZ+HusbXlpgQN95zp6o3GWyz+8/kvQ2EKGYGhavVRlv2IqKX5Mo45HJULCjhGk04bU=","trackType":""} May 06 12:47:13 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::syncState stateService play May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus play May 06 12:47:13 volumio volumio[3130]: info: Received an update from plugin. extracting info from payload May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:13 volumio volumio[3130]: info: ------------------------------ 51ms May 06 12:47:13 volumio volumio[3130]: info: ------------------------------ 56ms May 06 12:47:13 volumio volumio[3130]: info: sendMpdCommand status took 31 milliseconds May 06 12:47:13 volumio volumio[3130]: info: sendMpdCommand status took 31 milliseconds May 06 12:47:13 volumio volumio[3130]: info: sendMpdCommand playlistinfo took 28 milliseconds May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseState May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseTrackInfo May 06 12:47:13 volumio volumio[3130]: verbose: ControllerMpd::parseTrackInfo May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:47:13 volumio volumio[3130]: verbose: In UPNP mode May 06 12:47:13 volumio volumio[3130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Honey","artist":"王心凌","album":"My! Cyndi!","uri":"http://m801.music.126.net/20250506131211/bc58df7a7f19eb28f252424b9ac79312/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28017624820/62c5/09fd/00df/fc0341c303024bfe639037768ebbed15.mp3?vuutv=2JIqTJsdoCYOmPKEKS2zkg/MmME4olPQlzZPk567RzZ+HusbXlpgQN95zp6o3GWyz+8/kvQ2EKGYGhavVRlv2IqKX5Mo45HJULCjhGk04bU=","trackType":""} May 06 12:47:13 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::syncState stateService play May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus play May 06 12:47:13 volumio volumio[3130]: info: Received an update from plugin. extracting info from payload May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:13 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:47:13 volumio volumio[3130]: verbose: In UPNP mode May 06 12:47:13 volumio volumio[3130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Honey","artist":"王心凌","album":"My! Cyndi!","uri":"http://m801.music.126.net/20250506131211/bc58df7a7f19eb28f252424b9ac79312/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28017624820/62c5/09fd/00df/fc0341c303024bfe639037768ebbed15.mp3?vuutv=2JIqTJsdoCYOmPKEKS2zkg/MmME4olPQlzZPk567RzZ+HusbXlpgQN95zp6o3GWyz+8/kvQ2EKGYGhavVRlv2IqKX5Mo45HJULCjhGk04bU=","trackType":""} May 06 12:47:13 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::syncState stateService play May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus play May 06 12:47:13 volumio volumio[3130]: info: Received an update from plugin. extracting info from payload May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:13 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:13 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:14 volumio volumio[3130]: info: ------------------------------ 96ms May 06 12:47:14 volumio volumio[3130]: info: ------------------------------ 92ms May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: info: sendMpdCommand playlistinfo took 57 milliseconds May 06 12:47:14 volumio volumio[3130]: info: sendMpdCommand playlistinfo took 58 milliseconds May 06 12:47:14 volumio volumio[3130]: verbose: ControllerMpd::parseTrackInfo May 06 12:47:14 volumio volumio[3130]: verbose: ControllerMpd::parseTrackInfo May 06 12:47:14 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:47:14 volumio volumio[3130]: verbose: In UPNP mode May 06 12:47:14 volumio volumio[3130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Honey","artist":"王心凌","album":"My! Cyndi!","uri":"http://m801.music.126.net/20250506131211/bc58df7a7f19eb28f252424b9ac79312/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28017624820/62c5/09fd/00df/fc0341c303024bfe639037768ebbed15.mp3?vuutv=2JIqTJsdoCYOmPKEKS2zkg/MmME4olPQlzZPk567RzZ+HusbXlpgQN95zp6o3GWyz+8/kvQ2EKGYGhavVRlv2IqKX5Mo45HJULCjhGk04bU=","trackType":""} May 06 12:47:14 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::syncState stateService play May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus play May 06 12:47:14 volumio volumio[3130]: info: Received an update from plugin. extracting info from payload May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:14 volumio volumio[3130]: info: ControllerMpd::pushState May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::servicePushState May 06 12:47:14 volumio volumio[3130]: verbose: In UPNP mode May 06 12:47:14 volumio volumio[3130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Honey","artist":"王心凌","album":"My! Cyndi!","uri":"http://m801.music.126.net/20250506131211/bc58df7a7f19eb28f252424b9ac79312/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28017624820/62c5/09fd/00df/fc0341c303024bfe639037768ebbed15.mp3?vuutv=2JIqTJsdoCYOmPKEKS2zkg/MmME4olPQlzZPk567RzZ+HusbXlpgQN95zp6o3GWyz+8/kvQ2EKGYGhavVRlv2IqKX5Mo45HJULCjhGk04bU=","trackType":""} May 06 12:47:14 volumio volumio[3130]: verbose: CURRENT POSITION 9 May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::syncState stateService play May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::syncState currentStatus play May 06 12:47:14 volumio volumio[3130]: info: Received an update from plugin. extracting info from payload May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::pushState May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioPushState May 06 12:47:14 volumio volumio[3130]: info: ------------------------------ 143ms May 06 12:47:14 volumio volumio[3130]: info: ------------------------------ 143ms May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: info: CoreCommandRouter::volumioGetQueue May 06 12:47:14 volumio volumio[3130]: info: CoreStateMachine::getQueue May 06 12:47:14 volumio volumio[3130]: info: CorePlayQueue::getQueue May 06 12:47:14 volumio volumio[3130]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E5%BF%83%E5%87%8C/My!%20Cyndi!/6f9bcf5e-ec8e-4410-bce4-49d012701a9b.jpg' May 06 12:47:44 volumio volumio[3130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 12:47:44 volumio volumio[3130]: Error: connect ETIMEDOUT 157.240.0.18:443 May 06 12:47:44 volumio volumio[3130]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 06 12:47:44 volumio volumio[3130]: errno: -110, May 06 12:47:44 volumio volumio[3130]: code: 'ETIMEDOUT', May 06 12:47:44 volumio volumio[3130]: syscall: 'connect', May 06 12:47:44 volumio volumio[3130]: address: '157.240.0.18', May 06 12:47:44 volumio volumio[3130]: port: 443 May 06 12:47:44 volumio volumio[3130]: } May 06 12:47:44 volumio volumio[3130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 12:47:45 volumio sudo[4185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-06 12:46 May 06 12:47:45 volumio sudo[4185]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"