Jan 09 00:50:33 volumio volumio[27254]: info: CorePlayQueue::getTrack 4 Jan 09 00:50:33 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: mpd , goto Jan 09 00:50:40 volumio volumio[27254]: info: Preload queue cleared Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::ClearQueue Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::stop Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::stPlaybackTimer Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::updateTrackBlock Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrackBlock Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 4 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::serviceStop Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 4 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::serviceStop Jan 09 00:50:40 volumio volumio[27254]: info: ControllerMpd::stop Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::clearPlayQueue Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::saveQueue Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushQueue Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::addQueueItems Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::addQueueItems Jan 09 00:50:40 volumio volumio[27254]: info: Preload queue cleared Jan 09 00:50:40 volumio volumio[27254]: info: Adding Item to queue: artists://%E5%BC%A0%E9%9D%93%E9%A2%96 Jan 09 00:50:40 volumio volumio[27254]: info: Exploding uri artists://%E5%BC%A0%E9%9D%93%E9%A2%96 in service mpd Jan 09 00:50:40 volumio volumio[27254]: info: Jan 09 00:50:40 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:40 volumio volumio[27254]: info: sendMpdCommand stop took 41 milliseconds Jan 09 00:50:40 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushQueue Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::saveQueue Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::updateTrackBlock Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrackBlock Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPlay Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::play index 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::stop Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::play index undefined Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::startPlaybackTimer Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/金曲/倾听张靓颖/At Last.wav Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 00:50:40 volumio volumio[27254]: info: sendMpdCommand status took 34 milliseconds Jan 09 00:50:40 volumio volumio[27254]: info: sendMpdCommand stop took 8 milliseconds Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 00:50:40 volumio volumio[27254]: info: Jan 09 00:50:40 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:40 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:40 volumio volumio[27254]: info: Jan 09 00:50:40 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:40 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:40 volumio volumio[27254]: info: Jan 09 00:50:40 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:40 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:40 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 09 00:50:40 volumio volumio[27254]: info: sendMpdCommand clear took 7 milliseconds Jan 09 00:50:40 volumio volumio[27254]: info: sendMpdCommand status took 6 milliseconds Jan 09 00:50:40 volumio volumio[27254]: info: sendMpdCommand status took 5 milliseconds Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/金曲/倾听张靓颖/At Last.wav" Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:40 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:40 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Por Una Cabeza","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Por Una Cabeza.wav","trackType":"wav"} Jan 09 00:50:40 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::syncState stateService stop Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus stop Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:40 volumio volumio[27254]: info: No code Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:40 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: 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} Jan 09 00:50:40 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::syncState stateService stop Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus stop Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:40 volumio volumio[27254]: info: No code Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:40 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: 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} Jan 09 00:50:40 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::syncState stateService stop Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus stop Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:40 volumio volumio[27254]: info: No code Jan 09 00:50:40 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:40 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:40 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 155ms Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 117ms Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 116ms Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:41 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:41 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:41 volumio volumio[27254]: error: updateQueue error: null Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:41 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 117ms Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand add "NAS/nas/金曲/倾听张靓颖/At Last.wav" took 113 milliseconds Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand play Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 31ms Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 30ms Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 29ms Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand play took 28 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:41 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:41 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 17ms Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 15ms Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand status took 15 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand status took 14 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand status took 13 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand status took 11 milliseconds Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:41 volumio volumio[27254]: info: Jan 09 00:50:41 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand status took 11 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand status took 9 milliseconds Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:41 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus stop Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:41 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:41 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:41 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:41 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":371,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:41 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:41 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 77ms Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 151ms Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 148ms Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 147ms Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 115 milliseconds Jan 09 00:50:41 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 114 milliseconds Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:41 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:41 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:41 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:41 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:41 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:41 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:41 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 199ms Jan 09 00:50:41 volumio volumio[27254]: info: ------------------------------ 198ms Jan 09 00:50:46 volumio volumio[27254]: info: Preload queue cleared Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::ClearQueue Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::stop Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::stPlaybackTimer Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::updateTrackBlock Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrackBlock Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::serviceStop Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::serviceStop Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::stop Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::clearPlayQueue Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::saveQueue Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushQueue Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::addQueueItems Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::addQueueItems Jan 09 00:50:46 volumio volumio[27254]: info: Preload queue cleared Jan 09 00:50:46 volumio volumio[27254]: info: Adding Item to queue: artists://%E5%BC%A0%E9%9D%93%E9%A2%96 Jan 09 00:50:46 volumio volumio[27254]: info: Using cached record of: artists://%E5%BC%A0%E9%9D%93%E9%A2%96 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushQueue Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::saveQueue Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::updateTrackBlock Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrackBlock Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPlay Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::play index 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::stop Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::play index undefined Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::startPlaybackTimer Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/金曲/倾听张靓颖/At Last.wav Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand stop took 42 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand stop took 8 milliseconds Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:46 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand status took 4 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand clear took 4 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand status took 3 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand status took 2 milliseconds Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/金曲/倾听张靓颖/At Last.wav" Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: 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} Jan 09 00:50:46 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState stateService stop Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus stop Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: No code Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: 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} Jan 09 00:50:46 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState stateService stop Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus stop Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: No code Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 65ms Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 65ms Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:46 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:46 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:46 volumio volumio[27254]: error: updateQueue error: null Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:46 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 68ms Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 67 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand add "NAS/nas/金曲/倾听张靓颖/At Last.wav" took 67 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 5ms Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 4ms Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand play Jan 09 00:50:46 volumio volumio[27254]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 75ms Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:46 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:46 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 45ms Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand play took 43 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 43ms Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 43ms Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand status took 7 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand status took 6 milliseconds Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:46 volumio volumio[27254]: info: Jan 09 00:50:46 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand status took 12 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand status took 11 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand status took 8 milliseconds Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:46 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus stop Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:46 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:46 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 56ms Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 71ms Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand status took 59 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 54 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 53 milliseconds Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 51 milliseconds Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":225,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:46 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:46 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":413,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:46 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:46 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":864,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:46 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:46 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 166ms Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 164ms Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 161ms Jan 09 00:50:46 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 110 milliseconds Jan 09 00:50:46 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:46 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:46 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:46 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:46 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:46 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:46 volumio volumio[27254]: info: ------------------------------ 206ms Jan 09 00:50:48 volumio volumio[27254]: info: CoreCommandRouter::volumioSeek Jan 09 00:50:48 volumio volumio[27254]: info: CoreStateMachine::seek Jan 09 00:50:48 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:48 volumio volumio[27254]: info: TRACKBLOCK {"uri":"music-library/NAS/nas/金曲/倾听张靓颖/At Last.wav","service":"mpd","name":"At Last","artist":"张靓颖","album":"倾听张靓颖","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=29&web=%E5%BC%A0%E9%9D%93%E9%A2%96/%E5%80%BE%E5%90%AC%E5%BC%A0%E9%9D%93%E9%A2%96/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F%E9%87%91%E6%9B%B2%2F%E5%80%BE%E5%90%AC%E5%BC%A0%E9%9D%93%E9%A2%96&metadata=false","duration":192,"trackType":"wav","samplerate":"96 kHz","bitdepth":"32 bit","channels":2} Jan 09 00:50:48 volumio volumio[27254]: info: CoreStateMachine::startPlaybackTimer Jan 09 00:50:48 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:48 volumio volumio[27254]: info: ControllerMpd::seek Jan 09 00:50:48 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:48 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:48 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:48 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:49 volumio volumio[27254]: error: null Jan 09 00:50:49 volumio volumio[27254]: info: Jan 09 00:50:49 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:49 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:49 volumio volumio[27254]: info: Jan 09 00:50:49 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:49 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:49 volumio volumio[27254]: info: Jan 09 00:50:49 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:49 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:49 volumio volumio[27254]: info: sendMpdCommand status took 16 milliseconds Jan 09 00:50:49 volumio volumio[27254]: info: sendMpdCommand status took 15 milliseconds Jan 09 00:50:49 volumio volumio[27254]: info: sendMpdCommand status took 15 milliseconds Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:49 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 00:50:49 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:49 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:49 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:49 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":92990,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:49 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:49 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:49 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:49 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:49 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:49 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:49 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":92990,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:49 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:49 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:49 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:49 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:49 volumio volumio[27254]: info: ------------------------------ 85ms Jan 09 00:50:49 volumio volumio[27254]: info: ------------------------------ 83ms Jan 09 00:50:49 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 66 milliseconds Jan 09 00:50:49 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:49 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:49 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:49 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":92990,"duration":192,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:49 volumio volumio[27254]: verbose: CURRENT POSITION 0 Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:49 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:49 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:49 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:49 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:49 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:49 volumio volumio[27254]: info: ------------------------------ 108ms Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioNext Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::next Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::stop Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::stPlaybackTimer Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::updateTrackBlock Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrackBlock Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::serviceStop Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 0 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::serviceStop Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::stop Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand stop took 12 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::play index undefined Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::startPlaybackTimer Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::updateTrackBlock Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrackBlock Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand status took 5 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand stop took 4 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand status took 3 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand status took 2 milliseconds Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:55 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand clear took 3 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav" Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"At Last","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/At Last.wav","trackType":"wav"} Jan 09 00:50:55 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState stateService stop Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus stop Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: No code Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 09 00:50:55 volumio volumio[27254]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 39ms Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 36ms Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 35ms Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:55 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:55 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:55 volumio volumio[27254]: error: updateQueue error: null Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:55 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 34ms Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand add "NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav" took 32 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 3ms Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 3ms Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand play Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:55 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces system playlist update Jan 09 00:50:55 volumio volumio[27254]: info: Ignoring MPD Status Update Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 12ms Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand play took 11 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 11ms Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 11ms Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand status took 9 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand status took 9 milliseconds Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:55 volumio volumio[27254]: info: Jan 09 00:50:55 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand status took 10 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand status took 6 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand status took 4 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","trackType":"wav"} Jan 09 00:50:55 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus stop Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","trackType":"wav"} Jan 09 00:50:55 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:55 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 35ms Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 48ms Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand status took 39 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 37 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 37 milliseconds Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 37 milliseconds Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","trackType":"wav"} Jan 09 00:50:55 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:55 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":199,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","trackType":"wav"} Jan 09 00:50:55 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:55 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":225,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","trackType":"wav"} Jan 09 00:50:55 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:55 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 124ms Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 118ms Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 118ms Jan 09 00:50:55 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 130 milliseconds Jan 09 00:50:55 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:55 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":277,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","trackType":"wav"} Jan 09 00:50:55 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:55 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:55 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:55 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:55 volumio volumio[27254]: info: ------------------------------ 186ms Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::volumioSeek Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::seek Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: info: TRACKBLOCK {"uri":"music-library/NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","service":"mpd","name":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=29&web=%E5%BC%A0%E9%9D%93%E9%A2%96/%E5%80%BE%E5%90%AC%E5%BC%A0%E9%9D%93%E9%A2%96/extralarge&path=%2Fmnt%2FNAS%2Fnas%2F%E9%87%91%E6%9B%B2%2F%E5%80%BE%E5%90%AC%E5%BC%A0%E9%9D%93%E9%A2%96&metadata=false","duration":259,"trackType":"wav","samplerate":"96 kHz","bitdepth":"32 bit","channels":2} Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::startPlaybackTimer Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: info: ControllerMpd::seek Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:59 volumio volumio[27254]: error: null Jan 09 00:50:59 volumio volumio[27254]: info: Jan 09 00:50:59 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:59 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:59 volumio volumio[27254]: info: Jan 09 00:50:59 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:59 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:59 volumio volumio[27254]: info: Jan 09 00:50:59 volumio volumio[27254]: ---------------------------- MPD announces state update: player Jan 09 00:50:59 volumio volumio[27254]: info: ControllerMpd::getState Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand status Jan 09 00:50:59 volumio volumio[27254]: info: sendMpdCommand status took 22 milliseconds Jan 09 00:50:59 volumio volumio[27254]: info: sendMpdCommand status took 21 milliseconds Jan 09 00:50:59 volumio volumio[27254]: info: sendMpdCommand status took 20 milliseconds Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::parseState Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 00:50:59 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 09 00:50:59 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:59 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","trackType":"wav"} Jan 09 00:50:59 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:59 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:59 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","trackType":"wav"} Jan 09 00:50:59 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:59 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:59 volumio volumio[27254]: info: ------------------------------ 75ms Jan 09 00:50:59 volumio volumio[27254]: info: ------------------------------ 74ms Jan 09 00:50:59 volumio volumio[27254]: info: sendMpdCommand playlistinfo took 47 milliseconds Jan 09 00:50:59 volumio volumio[27254]: verbose: ControllerMpd::parseTrackInfo Jan 09 00:50:59 volumio volumio[27254]: info: ControllerMpd::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::servicePushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Concerto Pour Deux","artist":"张靓颖","album":"倾听张靓颖","uri":"NAS/nas/金曲/倾听张靓颖/Concerto Pour Deux.wav","trackType":"wav"} Jan 09 00:50:59 volumio volumio[27254]: verbose: CURRENT POSITION 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::syncState stateService play Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::syncState currentStatus play Jan 09 00:50:59 volumio volumio[27254]: info: Received an update from plugin. extracting info from payload Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:59 volumio volumio[27254]: info: CoreStateMachine::pushState Jan 09 00:50:59 volumio volumio[27254]: info: CorePlayQueue::getTrack 1 Jan 09 00:50:59 volumio volumio[27254]: info: CoreCommandRouter::volumioPushState Jan 09 00:50:59 volumio volumio[27254]: info: ------------------------------ 125ms Jan 09 00:51:03 volumio volumio[27254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 00:51:03 volumio volumio[27254]: Error: connect ETIMEDOUT 108.160.167.159:443 Jan 09 00:51:03 volumio volumio[27254]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 09 00:51:03 volumio volumio[27254]: errno: -110, Jan 09 00:51:03 volumio volumio[27254]: code: 'ETIMEDOUT', Jan 09 00:51:03 volumio volumio[27254]: syscall: 'connect', Jan 09 00:51:03 volumio volumio[27254]: address: '108.160.167.159', Jan 09 00:51:03 volumio volumio[27254]: port: 443 Jan 09 00:51:03 volumio volumio[27254]: } Jan 09 00:51:03 volumio volumio[27254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 00:51:04 volumio sudo[28379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-09 00:50' Jan 09 00:51:04 volumio sudo[28379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"