-- Logs begin at Fri 2026-01-09 20:35:53 CST, end at Fri 2026-01-09 21:31:27 CST. -- Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPlay Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::play index 38 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stop Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::updateTrackBlock Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrackBlock Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 14 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::serviceStop Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 14 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::serviceStop Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: ControllerMpd::stop Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:02 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: sendMpdCommand stop took 43 milliseconds Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::play index undefined Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::startPlaybackTimer Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:02 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:02 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 10 milliseconds Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: sendMpdCommand stop took 8 milliseconds Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 5 milliseconds Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 9 milliseconds Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"珠玉","artist":"单依纯","album":"珠玉","uri":"INTERNAL/musics/6月更新新歌/单依纯-珠玉.flac","trackType":"flac"} Jan 09 21:30:02 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 38 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService stop Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: No code Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:02 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 103ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand clear took 97 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 96 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 86 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac" Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 09 21:30:03 volumio-x86-784 volumio[3797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 112ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 110ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: error: updateQueue error: null Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 38ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand add "INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac" took 34 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 30ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 29ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 71ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand play took 64 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 63ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 55ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 28 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 28 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 29 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 19 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:03 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 33 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 20 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":386,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1242 Kbps","isStreaming":false,"title":"兰花草 (我从山中来)","artist":"拾柒兄弟","album":"兰花草 (我从山中来)","uri":"INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac","trackType":"flac"} Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":386,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1242 Kbps","isStreaming":false,"title":"兰花草 (我从山中来)","artist":"拾柒兄弟","album":"兰花草 (我从山中来)","uri":"INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac","trackType":"flac"} Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":386,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1265 Kbps","isStreaming":false,"title":"兰花草 (我从山中来)","artist":"拾柒兄弟","album":"兰花草 (我从山中来)","uri":"INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac","trackType":"flac"} Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":564,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1292 Kbps","isStreaming":false,"title":"兰花草 (我从山中来)","artist":"拾柒兄弟","album":"兰花草 (我从山中来)","uri":"INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac","trackType":"flac"} Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 234ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 296ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 291ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 291ms Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 228 milliseconds Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1529 Kbps","isStreaming":false,"title":"兰花草 (我从山中来)","artist":"拾柒兄弟","album":"兰花草 (我从山中来)","uri":"INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac","trackType":"flac"} Jan 09 21:30:03 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:03 volumio-x86-784 volumio[3797]: info: ------------------------------ 358ms Jan 09 21:30:05 volumio-x86-784 volumio[3797]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Jan 09 21:30:05 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioGetState Jan 09 21:30:05 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:17 volumio-x86-784 volumio[3797]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 09 21:30:17 volumio-x86-784 volumio[3797]: info: Retrying Login Due to Network Error Jan 09 21:30:27 volumio-x86-784 volumio[3797]: info: MyVolumio login type: Token Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::servicePause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ControllerMpd::pause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand pause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:36 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: sendMpdCommand pause took 2 milliseconds Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:36 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:36 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 5 milliseconds Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 4 milliseconds Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 2 milliseconds Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":33811,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1906 Kbps","isStreaming":false,"title":"兰花草 (我从山中来)","artist":"拾柒兄弟","album":"兰花草 (我从山中来)","uri":"INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac","trackType":"flac"} Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService pause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus pause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":33811,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1906 Kbps","isStreaming":false,"title":"兰花草 (我从山中来)","artist":"拾柒兄弟","album":"兰花草 (我从山中来)","uri":"INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac","trackType":"flac"} Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService pause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus pause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":33811,"duration":196,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1906 Kbps","isStreaming":false,"title":"兰花草 (我从山中来)","artist":"拾柒兄弟","album":"兰花草 (我从山中来)","uri":"INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac","trackType":"flac"} Jan 09 21:30:36 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService pause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus pause Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ------------------------------ 87ms Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ------------------------------ 83ms Jan 09 21:30:36 volumio-x86-784 volumio[3797]: info: ------------------------------ 81ms Jan 09 21:30:36 volumio-x86-784 sudo[11860]: pam_unix(sudo:session): session closed for user root Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPlay Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::play index 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::updateTrackBlock Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrackBlock Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::serviceStop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 38 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::serviceStop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand stop took 11 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::play index undefined Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::startPlaybackTimer Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/6月更新新歌/轻烟大叔-你怎么舍得我难过 (最爱你的人是我).flac Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 3 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand stop took 2 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand clear took 6 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 4 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/6月更新新歌/轻烟大叔-你怎么舍得我难过 (最爱你的人是我).flac" Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"兰花草 (我从山中来)","artist":"拾柒兄弟","album":"兰花草 (我从山中来)","uri":"INTERNAL/musics/6月更新新歌/拾柒兄弟-兰花草 (我从山中来).flac","trackType":"flac"} Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: No code Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: 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 21:30:37 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: No code Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 108ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 97ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: error: updateQueue error: null Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 99 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 98ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand add "INTERNAL/musics/6月更新新歌/轻烟大叔-你怎么舍得我难过 (最爱你的人是我).flac" took 95 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand play Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: 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 21:30:37 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: No code Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 278ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 265ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 266ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand play took 264 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 82ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 25 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 24 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 20ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 18 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 10ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:37 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 31 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 31 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 3 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":217,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1703 Kbps","isStreaming":false,"title":"你怎么舍得我难过 (最爱你的人是我)","artist":"轻烟大叔","album":"你怎么舍得我难过 (最爱你的人是我)","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-你怎么舍得我难过 (最爱你的人是我).flac","trackType":"flac"} Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":217,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1703 Kbps","isStreaming":false,"title":"你怎么舍得我难过 (最爱你的人是我)","artist":"轻烟大叔","album":"你怎么舍得我难过 (最爱你的人是我)","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-你怎么舍得我难过 (最爱你的人是我).flac","trackType":"flac"} Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":217,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1703 Kbps","isStreaming":false,"title":"你怎么舍得我难过 (最爱你的人是我)","artist":"轻烟大叔","album":"你怎么舍得我难过 (最爱你的人是我)","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-你怎么舍得我难过 (最爱你的人是我).flac","trackType":"flac"} Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 97ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 132ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 121ms Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 68 milliseconds Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":742,"duration":217,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1703 Kbps","isStreaming":false,"title":"你怎么舍得我难过 (最爱你的人是我)","artist":"轻烟大叔","album":"你怎么舍得我难过 (最爱你的人是我)","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-你怎么舍得我难过 (最爱你的人是我).flac","trackType":"flac"} Jan 09 21:30:37 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:37 volumio-x86-784 volumio[3797]: info: ------------------------------ 130ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPlay Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::play index 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stop Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::updateTrackBlock Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrackBlock Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::serviceStop Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 75 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::serviceStop Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::stop Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand stop took 30 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::play index undefined Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::startPlaybackTimer Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 20 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand stop took 15 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 6 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 2 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand clear took 8 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac" Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你怎么舍得我难过 (最爱你的人是我)","artist":"轻烟大叔","album":"你怎么舍得我难过 (最爱你的人是我)","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-你怎么舍得我难过 (最爱你的人是我).flac","trackType":"flac"} Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService stop Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: No code Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 09 21:30:43 volumio-x86-784 volumio[3797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 88ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 79ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 70ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: error: updateQueue error: null Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 70ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand add "INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac" took 66 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 12ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 10ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 48ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand play took 43 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 38ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 36ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 20 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 20 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 12 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 8 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:43 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 30 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 26 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 25 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"要走的人留不住","artist":"轻烟大叔","album":"要走的人留不住","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac","trackType":"flac"} Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"要走的人留不住","artist":"轻烟大叔","album":"要走的人留不住","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac","trackType":"flac"} Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1323 Kbps","isStreaming":false,"title":"要走的人留不住","artist":"轻烟大叔","album":"要走的人留不住","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac","trackType":"flac"} Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1300 Kbps","isStreaming":false,"title":"要走的人留不住","artist":"轻烟大叔","album":"要走的人留不住","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac","trackType":"flac"} Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 104ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 139ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 140ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 133ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 97 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 98 milliseconds Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1301 Kbps","isStreaming":false,"title":"要走的人留不住","artist":"轻烟大叔","album":"要走的人留不住","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac","trackType":"flac"} Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1302 Kbps","isStreaming":false,"title":"要走的人留不住","artist":"轻烟大叔","album":"要走的人留不住","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac","trackType":"flac"} Jan 09 21:30:43 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 207ms Jan 09 21:30:43 volumio-x86-784 volumio[3797]: info: ------------------------------ 210ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPlay Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::play index 80 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stop Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::updateTrackBlock Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrackBlock Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::serviceStop Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 77 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::serviceStop Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ControllerMpd::stop Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand stop took 15 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::play index undefined Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::startPlaybackTimer Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 14 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand stop took 11 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 9 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 5 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand clear took 17 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac" Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"要走的人留不住","artist":"轻烟大叔","album":"要走的人留不住","uri":"INTERNAL/musics/6月更新新歌/轻烟大叔-要走的人留不住.flac","trackType":"flac"} Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService stop Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: No code Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 09 21:30:45 volumio-x86-784 volumio[3797]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ------------------------------ 80ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ------------------------------ 73ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ------------------------------ 70ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: error: updateQueue error: null Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ------------------------------ 44ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand add "INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac" took 41 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ------------------------------ 7ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ------------------------------ 5ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand play Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces system playlist update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Ignoring MPD Status Update Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ------------------------------ 52ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: sendMpdCommand play took 49 milliseconds Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ------------------------------ 48ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ------------------------------ 48ms Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:45 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:45 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:45 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:46 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 33 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 34 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 31 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 16 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:46 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: Jan 09 21:30:46 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 66 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 59 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 40 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 39 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1046 Kbps","isStreaming":false,"title":"问情（山川载不动太多悲哀）","artist":"阿美呀","album":"问情（山川载不动太多悲哀）","uri":"INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac","trackType":"flac"} Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus stop Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1046 Kbps","isStreaming":false,"title":"问情（山川载不动太多悲哀）","artist":"阿美呀","album":"问情（山川载不动太多悲哀）","uri":"INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac","trackType":"flac"} Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1046 Kbps","isStreaming":false,"title":"问情（山川载不动太多悲哀）","artist":"阿美呀","album":"问情（山川载不动太多悲哀）","uri":"INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac","trackType":"flac"} Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1048 Kbps","isStreaming":false,"title":"问情（山川载不动太多悲哀）","artist":"阿美呀","album":"问情（山川载不动太多悲哀）","uri":"INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac","trackType":"flac"} Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ------------------------------ 181ms Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ------------------------------ 264ms Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ------------------------------ 259ms Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ------------------------------ 259ms Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 166 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 167 milliseconds Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1048 Kbps","isStreaming":false,"title":"问情（山川载不动太多悲哀）","artist":"阿美呀","album":"问情（山川载不动太多悲哀）","uri":"INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac","trackType":"flac"} Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1048 Kbps","isStreaming":false,"title":"问情（山川载不动太多悲哀）","artist":"阿美呀","album":"问情（山川载不动太多悲哀）","uri":"INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac","trackType":"flac"} Jan 09 21:30:46 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus play Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: Received an update from plugin. extracting info from payload Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ------------------------------ 291ms Jan 09 21:30:46 volumio-x86-784 volumio[3797]: info: ------------------------------ 293ms Jan 09 21:30:47 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioGetState Jan 09 21:30:47 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:47 volumio-x86-784 sudo[12327]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 09 21:30:47 volumio-x86-784 sudo[12327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 21:30:50 volumio-x86-784 volumio[3797]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Jan 09 21:30:50 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioGetState Jan 09 21:30:50 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:52 volumio-x86-784 volumio[3797]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Jan 09 21:30:52 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioGetState Jan 09 21:30:52 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:30:57 volumio-x86-784 volumio[3797]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 09 21:30:57 volumio-x86-784 volumio[3797]: info: Retrying Login Due to Network Error Jan 09 21:31:07 volumio-x86-784 volumio[3797]: info: MyVolumio login type: Token Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::servicePause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ControllerMpd::pause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand pause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: Jan 09 21:31:24 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: sendMpdCommand pause took 3 milliseconds Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: Jan 09 21:31:24 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: Jan 09 21:31:24 volumio-x86-784 volumio[3797]: ---------------------------- MPD announces state update: player Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ControllerMpd::getState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand status Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 4 milliseconds Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 3 milliseconds Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: sendMpdCommand status took 2 milliseconds Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: ControllerMpd::parseTrackInfo Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":39103,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1658 Kbps","isStreaming":false,"title":"问情（山川载不动太多悲哀）","artist":"阿美呀","album":"问情（山川载不动太多悲哀）","uri":"INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac","trackType":"flac"} Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService pause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus pause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":39103,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1658 Kbps","isStreaming":false,"title":"问情（山川载不动太多悲哀）","artist":"阿美呀","album":"问情（山川载不动太多悲哀）","uri":"INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac","trackType":"flac"} Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService pause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus pause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ControllerMpd::pushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::servicePushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":39103,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1658 Kbps","isStreaming":false,"title":"问情（山川载不动太多悲哀）","artist":"阿美呀","album":"问情（山川载不动太多悲哀）","uri":"INTERNAL/musics/6月更新新歌/阿美呀-问情（山川载不动太多悲哀）.flac","trackType":"flac"} Jan 09 21:31:24 volumio-x86-784 volumio[3797]: verbose: CURRENT POSITION 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState stateService pause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::syncState currentStatus pause Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::pushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CorePlayQueue::getTrack 80 Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreCommandRouter::volumioPushState Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: CoreStateMachine::stPlaybackTimer Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ------------------------------ 40ms Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ------------------------------ 36ms Jan 09 21:31:24 volumio-x86-784 volumio[3797]: info: ------------------------------ 39ms Jan 09 21:31:24 volumio-x86-784 sudo[12327]: pam_unix(sudo:session): session closed for user root Jan 09 21:31:26 volumio-x86-784 volumio[3797]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 21:31:26 volumio-x86-784 volumio[3797]: Error: connect ETIMEDOUT 128.121.146.235:443 Jan 09 21:31:26 volumio-x86-784 volumio[3797]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 09 21:31:26 volumio-x86-784 volumio[3797]: errno: -110, Jan 09 21:31:26 volumio-x86-784 volumio[3797]: code: 'ETIMEDOUT', Jan 09 21:31:26 volumio-x86-784 volumio[3797]: syscall: 'connect', Jan 09 21:31:26 volumio-x86-784 volumio[3797]: address: '128.121.146.235', Jan 09 21:31:26 volumio-x86-784 volumio[3797]: port: 443 Jan 09 21:31:26 volumio-x86-784 volumio[3797]: } Jan 09 21:31:26 volumio-x86-784 volumio[3797]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 21:31:27 volumio-x86-784 sudo[12451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-09 21:30 Jan 09 21:31:27 volumio-x86-784 sudo[12451]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"