-- Logs begin at Thu 2024-09-05 15:15:29 CST, end at Thu 2024-09-05 15:28:11 CST. -- Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- Client requests Volumio next Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioNext Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::next Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::stop Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::updateTrackBlock Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrackBlock Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::stPlaybackTimer Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 0 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::serviceStop Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 0 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::serviceStop Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::stop Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand stop took 7 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::play index undefined Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::startPlaybackTimer Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::clearAddPlayTracks USB/8294-59D0/music0905/Track08.wav Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::updateTrackBlock Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrackBlock Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand status took 5 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand stop took 3 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand status took 2 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand status took 1 milliseconds Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:26 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand clear took 2 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand add "USB/8294-59D0/music0905/Track08.wav" Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: 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":"4蔡琴 流行歌曲 - 渡口.wav","artist":null,"album":null,"uri":"USB/8294-59D0/music0905/4蔡琴 流行歌曲 - 渡口.wav","trackType":"wav"} Sep 05 15:27:26 volumio volumio[2314]: verbose: CURRENT POSITION 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState stateService stop Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus stop Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: No code Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 15:27:26 volumio volumio[2314]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 25ms Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 17ms Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 16ms Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:26 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:26 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:26 volumio volumio[2314]: error: updateQueue error: null Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:26 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 15ms Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand add "USB/8294-59D0/music0905/Track08.wav" took 13 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 2ms Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 2ms Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand play Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:26 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:26 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 4ms Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand play took 3 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 2ms Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 2ms Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand status took 10 milliseconds Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand status took 10 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand status took 10 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track08.wav","artist":null,"album":null,"uri":"USB/8294-59D0/music0905/Track08.wav","trackType":"wav"} Sep 05 15:27:26 volumio volumio[2314]: verbose: CURRENT POSITION 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus stop Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 14ms Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:26 volumio volumio[2314]: info: Sep 05 15:27:26 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand status took 8 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand status took 2 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand status took 1 milliseconds Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track08.wav","artist":null,"album":null,"uri":"USB/8294-59D0/music0905/Track08.wav","trackType":"wav"} Sep 05 15:27:26 volumio volumio[2314]: verbose: CURRENT POSITION 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:26 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track08.wav","artist":null,"album":null,"uri":"USB/8294-59D0/music0905/Track08.wav","trackType":"wav"} Sep 05 15:27:26 volumio volumio[2314]: verbose: CURRENT POSITION 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:26 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 34ms Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 33ms Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 05 15:27:26 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:26 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track08.wav","artist":null,"album":null,"uri":"USB/8294-59D0/music0905/Track08.wav","trackType":"wav"} Sep 05 15:27:26 volumio volumio[2314]: verbose: CURRENT POSITION 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:26 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track08.wav","artist":null,"album":null,"uri":"USB/8294-59D0/music0905/Track08.wav","trackType":"wav"} Sep 05 15:27:26 volumio volumio[2314]: verbose: CURRENT POSITION 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:26 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track08.wav","artist":null,"album":null,"uri":"USB/8294-59D0/music0905/Track08.wav","trackType":"wav"} Sep 05 15:27:26 volumio volumio[2314]: verbose: CURRENT POSITION 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:26 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:26 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:26 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 35ms Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 32ms Sep 05 15:27:26 volumio volumio[2314]: info: ------------------------------ 28ms Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- Client requests Volumio next Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioNext Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::next Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::stop Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::stPlaybackTimer Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::updateTrackBlock Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrackBlock Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::serviceStop Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 1 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::serviceStop Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::stop Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand stop took 7 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::play index undefined Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::startPlaybackTimer Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::clearAddPlayTracks USB/8294-59D0/music0905/暗杠 - 童话镇 [mqms2].flac Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::updateTrackBlock Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrackBlock Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand status took 3 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand stop took 2 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand status took 1 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand status took 1 milliseconds Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:33 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand clear took 1 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand add "USB/8294-59D0/music0905/暗杠 - 童话镇 [mqms2].flac" Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: 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":"Track08.wav","artist":null,"album":null,"uri":"USB/8294-59D0/music0905/Track08.wav","trackType":"wav"} Sep 05 15:27:33 volumio volumio[2314]: verbose: CURRENT POSITION 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState stateService stop Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus stop Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: No code Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 15:27:33 volumio volumio[2314]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 14ms Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 12ms Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 12ms Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:33 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:33 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:33 volumio volumio[2314]: error: updateQueue error: null Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:33 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 11ms Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand add "USB/8294-59D0/music0905/暗杠 - 童话镇 [mqms2].flac" took 10 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 2ms Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 1ms Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand play Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:33 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces system playlist update Sep 05 15:27:33 volumio volumio[2314]: info: Ignoring MPD Status Update Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 3ms Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand play took 3 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 2ms Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 2ms Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand status took 10 milliseconds Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand status took 11 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand status took 11 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"童话镇","artist":"暗杠","album":null,"uri":"USB/8294-59D0/music0905/暗杠 - 童话镇 [mqms2].flac","trackType":"flac"} Sep 05 15:27:33 volumio volumio[2314]: verbose: CURRENT POSITION 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus stop Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 14ms Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:33 volumio volumio[2314]: info: Sep 05 15:27:33 volumio volumio[2314]: ---------------------------- MPD announces state update: player Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::getState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand status Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand status took 3 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand status took 1 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand status took 1 milliseconds Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseState Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42,"duration":250,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"童话镇","artist":"暗杠","album":null,"uri":"USB/8294-59D0/music0905/暗杠 - 童话镇 [mqms2].flac","trackType":"flac"} Sep 05 15:27:33 volumio volumio[2314]: verbose: CURRENT POSITION 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:33 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":250,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"132 Kbps","isStreaming":false,"title":"童话镇","artist":"暗杠","album":null,"uri":"USB/8294-59D0/music0905/暗杠 - 童话镇 [mqms2].flac","trackType":"flac"} Sep 05 15:27:33 volumio volumio[2314]: verbose: CURRENT POSITION 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:33 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 27ms Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 27ms Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 05 15:27:33 volumio volumio[2314]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:33 volumio volumio[2314]: verbose: ControllerMpd::parseTrackInfo Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":250,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"725 Kbps","isStreaming":false,"title":"童话镇","artist":"暗杠","album":null,"uri":"USB/8294-59D0/music0905/暗杠 - 童话镇 [mqms2].flac","trackType":"flac"} Sep 05 15:27:33 volumio volumio[2314]: verbose: CURRENT POSITION 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:33 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":250,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"941 Kbps","isStreaming":false,"title":"童话镇","artist":"暗杠","album":null,"uri":"USB/8294-59D0/music0905/暗杠 - 童话镇 [mqms2].flac","trackType":"flac"} Sep 05 15:27:33 volumio volumio[2314]: verbose: CURRENT POSITION 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:33 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: ControllerMpd::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::servicePushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":250,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"783 Kbps","isStreaming":false,"title":"童话镇","artist":"暗杠","album":null,"uri":"USB/8294-59D0/music0905/暗杠 - 童话镇 [mqms2].flac","trackType":"flac"} Sep 05 15:27:33 volumio volumio[2314]: verbose: CURRENT POSITION 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState stateService play Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::syncState currentStatus play Sep 05 15:27:33 volumio volumio[2314]: info: Received an update from plugin. extracting info from payload Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:33 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:33 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 31ms Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 28ms Sep 05 15:27:33 volumio volumio[2314]: info: ------------------------------ 28ms Sep 05 15:27:46 volumio volumio[2314]: info: VolumeController::SetAlsaVolume46 Sep 05 15:27:46 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:46 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:46 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:46 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:47 volumio volumio[2314]: info: VolumeController::SetAlsaVolume24 Sep 05 15:27:47 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:47 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:47 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:47 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:47 volumio volumio[2314]: info: VolumeController::SetAlsaVolume22 Sep 05 15:27:47 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:47 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:47 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:47 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:47 volumio volumio[2314]: info: VolumeController::SetAlsaVolume21 Sep 05 15:27:47 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:47 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:47 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:47 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:55 volumio volumio[2314]: info: VolumeController::SetAlsaVolume22 Sep 05 15:27:55 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:55 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:55 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:55 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:55 volumio volumio[2314]: info: VolumeController::SetAlsaVolume25 Sep 05 15:27:55 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:55 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:55 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:55 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:56 volumio volumio[2314]: info: VolumeController::SetAlsaVolume29 Sep 05 15:27:56 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:56 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:56 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:56 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:57 volumio volumio[2314]: info: VolumeController::SetAlsaVolume43 Sep 05 15:27:57 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:57 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:57 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:57 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:57 volumio volumio[2314]: info: VolumeController::SetAlsaVolume46 Sep 05 15:27:57 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:57 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:57 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:57 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:58 volumio volumio[2314]: info: VolumeController::SetAlsaVolume44 Sep 05 15:27:58 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:58 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:58 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:58 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:59 volumio volumio[2314]: info: VolumeController::SetAlsaVolume39 Sep 05 15:27:59 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:59 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:59 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:59 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:59 volumio volumio[2314]: info: VolumeController::SetAlsaVolume38 Sep 05 15:27:59 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:59 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:59 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:59 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:27:59 volumio volumio[2314]: info: VolumeController::SetAlsaVolume37 Sep 05 15:27:59 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:27:59 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:27:59 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:27:59 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:28:00 volumio volumio[2314]: info: VolumeController::SetAlsaVolume36 Sep 05 15:28:00 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:28:00 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:28:00 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:28:00 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:28:00 volumio volumio[2314]: info: VolumeController::SetAlsaVolume35 Sep 05 15:28:00 volumio volumio[2314]: info: CoreStateMachine::pushState Sep 05 15:28:00 volumio volumio[2314]: info: CorePlayQueue::getTrack 2 Sep 05 15:28:00 volumio volumio[2314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 15:28:00 volumio volumio[2314]: info: CoreCommandRouter::volumioPushState Sep 05 15:28:04 volumio ntpd[997]: 5.79.108.34 local addr 192.168.100.80 -> Sep 05 15:28:11 volumio volumio[2314]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 15:28:11 volumio volumio[2314]: Error: connect ETIMEDOUT 104.244.46.93:443 Sep 05 15:28:11 volumio volumio[2314]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 05 15:28:11 volumio volumio[2314]: errno: -110, Sep 05 15:28:11 volumio volumio[2314]: code: 'ETIMEDOUT', Sep 05 15:28:11 volumio volumio[2314]: syscall: 'connect', Sep 05 15:28:11 volumio volumio[2314]: address: '104.244.46.93', Sep 05 15:28:11 volumio volumio[2314]: port: 443 Sep 05 15:28:11 volumio volumio[2314]: } Sep 05 15:28:11 volumio volumio[2314]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 15:28:11 volumio sudo[2648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-05 15:27 Sep 05 15:28:11 volumio sudo[2648]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"