-- Logs begin at Wed 2025-10-29 22:56:45 CST, end at Wed 2025-10-29 23:30:27 CST. -- Oct 29 23:29:11 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 23:29:11 volumio volumio[957]: info: CURURI: albums:///%E5%BC%A0%E5%86%AC%E7%8E%B2-%E8%AF%B4%E5%A5%BD%E7%9A%84%E4%B8%80%E8%BE%88%E5%AD%90(DSD) Oct 29 23:29:11 volumio volumio[957]: info: Preload queue cleared Oct 29 23:29:11 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav Oct 29 23:29:12 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav in service mpd Oct 29 23:29:12 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=/%E5%BC%A0%E5%86%AC%E7%8E%B2-%E8%AF%B4%E5%A5%BD%E7%9A%84%E4%B8%80%E8%BE%88%E5%AD%90(DSD)/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E5%BC%A0%E5%86%AC%E7%8E%B2-%E8%AF%B4%E5%A5%BD%E7%9A%84%E4%B8%80%E8%BE%88%E5%AD%90%20%20%5BWAV%2BCUE%5D%2FCDImage.wav&metadata=false Oct 29 23:29:12 volumio volumio[957]: info: URI /mnt/NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav Oct 29 23:29:14 volumio volumio[957]: info: Preload queue cleared Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::ClearQueue Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::stop Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::stPlaybackTimer Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::serviceStop Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::serviceStop Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::stop Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::clearPlayQueue Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::saveQueue Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::addQueueItems Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::addQueueItems Oct 29 23:29:14 volumio volumio[957]: info: Preload queue cleared Oct 29 23:29:14 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav Oct 29 23:29:14 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::saveQueue Oct 29 23:29:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPlay Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::play index 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::stop Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::play index undefined Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand stop took 36 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand stop took 7 milliseconds Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:14 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 17 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand clear took 16 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 15 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 13 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 13 milliseconds Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav" Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: 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} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: No code Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: 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} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: No code Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: 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} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: No code Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 164ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 163ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 164ms Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:14 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:14 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:14 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:14 volumio volumio[957]: error: updateQueue error: null Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:14 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 164ms Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand playlistinfo took 161 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand add "NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav" took 160 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 9ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 8ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 7ms Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play Oct 29 23:29:14 volumio volumio[957]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 185ms Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:14 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:14 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:14 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 44ms Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand play took 39 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 38ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 36ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 35ms Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 46 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 43 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 42 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 40 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 5 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3972,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张冬玲-说好的一辈子(DSD)","artist":null,"album":"张冬玲-说好的一辈子(DSD)","uri":"NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav","trackType":"wav"} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3972,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张冬玲-说好的一辈子(DSD)","artist":null,"album":"张冬玲-说好的一辈子(DSD)","uri":"NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav","trackType":"wav"} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:14 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3972,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张冬玲-说好的一辈子(DSD)","artist":null,"album":"张冬玲-说好的一辈子(DSD)","uri":"NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav","trackType":"wav"} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:14 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 86ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 126ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 125ms Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: Oct 29 23:29:14 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand playlistinfo took 88 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand playlistinfo took 83 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 8 milliseconds Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3972,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张冬玲-说好的一辈子(DSD)","artist":null,"album":"张冬玲-说好的一辈子(DSD)","uri":"NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav","trackType":"wav"} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:14 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3972,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张冬玲-说好的一辈子(DSD)","artist":null,"album":"张冬玲-说好的一辈子(DSD)","uri":"NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav","trackType":"wav"} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:14 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 206ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 169ms Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 97 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand status took 96 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand playlistinfo took 91 milliseconds Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":3972,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张冬玲-说好的一辈子(DSD)","artist":null,"album":"张冬玲-说好的一辈子(DSD)","uri":"NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav","trackType":"wav"} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:14 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 145ms Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 29 23:29:14 volumio volumio[957]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:14 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":3972,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张冬玲-说好的一辈子(DSD)","artist":null,"album":"张冬玲-说好的一辈子(DSD)","uri":"NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav","trackType":"wav"} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:14 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":3972,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"张冬玲-说好的一辈子(DSD)","artist":null,"album":"张冬玲-说好的一辈子(DSD)","uri":"NAS/music/music/张冬玲-说好的一辈子 [WAV+CUE]/CDImage.wav","trackType":"wav"} Oct 29 23:29:14 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:14 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:14 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:14 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 213ms Oct 29 23:29:14 volumio volumio[957]: info: ------------------------------ 213ms Oct 29 23:29:18 volumio volumio[957]: info: CoreCommandRouter::volumioplayNextItems Oct 29 23:29:18 volumio volumio[957]: info: CoreStateMachine::playNextItems Oct 29 23:29:18 volumio volumio[957]: info: CorePlayQueue::addPlayNextItems Oct 29 23:29:18 volumio volumio[957]: info: Preload queue cleared Oct 29 23:29:18 volumio volumio[957]: info: Adding Item to play next: albums:///%E5%BC%A0%E5%86%AC%E7%8E%B2-%E8%AF%B4%E5%A5%BD%E7%9A%84%E4%B8%80%E8%BE%88%E5%AD%90(DSD) Oct 29 23:29:18 volumio volumio[957]: info: Exploding uri albums:///%E5%BC%A0%E5%86%AC%E7%8E%B2-%E8%AF%B4%E5%A5%BD%E7%9A%84%E4%B8%80%E8%BE%88%E5%AD%90(DSD) in service mpd Oct 29 23:29:18 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Oct 29 23:29:18 volumio volumio[957]: info: CorePlayQueue::saveQueue Oct 29 23:29:18 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Oct 29 23:29:18 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Oct 29 23:29:30 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 29 23:29:30 volumio volumio[957]: info: CURURI: albums://%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4 Oct 29 23:29:31 volumio volumio[957]: info: Preload queue cleared Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/New Boy.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/妈妈，我....flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/在希望的田野上.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/那些花儿.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/我去2000年.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/旅途.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/别，千万别.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/白桦林.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/活着.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/召唤.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/九月.flac Oct 29 23:29:31 volumio volumio[957]: info: Preloading song: music-library/NAS/music/music/我去2000年/火车开往冬天.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/New Boy.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2FNew%20Boy.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/New Boy.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/妈妈，我....flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: No valid Plugin REST Endpoint Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E5%A6%88%E5%A6%88%EF%BC%8C%E6%88%91....flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/妈妈，我....flac Oct 29 23:29:31 volumio volumio[957]: info: No valid Plugin REST Endpoint Oct 29 23:29:31 volumio volumio[957]: info: No valid Plugin REST Endpoint Oct 29 23:29:31 volumio volumio[957]: info: No valid Plugin REST Endpoint Oct 29 23:29:31 volumio volumio[957]: info: No valid Plugin REST Endpoint Oct 29 23:29:31 volumio volumio[957]: info: No valid Plugin REST Endpoint Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/在希望的田野上.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: No valid Plugin REST Endpoint Oct 29 23:29:31 volumio volumio[957]: info: No valid Plugin REST Endpoint Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E5%9C%A8%E5%B8%8C%E6%9C%9B%E7%9A%84%E7%94%B0%E9%87%8E%E4%B8%8A.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/在希望的田野上.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/那些花儿.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E9%82%A3%E4%BA%9B%E8%8A%B1%E5%84%BF.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/那些花儿.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/我去2000年.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/旅途.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/我去2000年.flac Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E6%97%85%E9%80%94.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/旅途.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/别，千万别.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E5%88%AB%EF%BC%8C%E5%8D%83%E4%B8%87%E5%88%AB.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/别，千万别.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/白桦林.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E7%99%BD%E6%A1%A6%E6%9E%97.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/白桦林.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/活着.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E6%B4%BB%E7%9D%80.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/活着.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/召唤.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E5%8F%AC%E5%94%A4.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/召唤.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/九月.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E4%B9%9D%E6%9C%88.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/九月.flac Oct 29 23:29:31 volumio volumio[957]: info: Exploding uri music-library/NAS/music/music/我去2000年/火车开往冬天.flac in service mpd Oct 29 23:29:31 volumio volumio[957]: info: ALBUMART /albumart?cacheid=166&web=%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmusic%2F%E6%88%91%E5%8E%BB2000%E5%B9%B4%2F%E7%81%AB%E8%BD%A6%E5%BC%80%E5%BE%80%E5%86%AC%E5%A4%A9.flac&metadata=false Oct 29 23:29:31 volumio volumio[957]: info: URI /mnt/NAS/music/music/我去2000年/火车开往冬天.flac Oct 29 23:29:33 volumio volumio[957]: info: Preload queue cleared Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::ClearQueue Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::stop Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::stPlaybackTimer Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::serviceStop Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::serviceStop Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::stop Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::clearPlayQueue Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::saveQueue Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::addQueueItems Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::addQueueItems Oct 29 23:29:33 volumio volumio[957]: info: Preload queue cleared Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/New Boy.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/New Boy.flac Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::saveQueue Oct 29 23:29:33 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPlay Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::play index 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::addQueueItems Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::addQueueItems Oct 29 23:29:33 volumio volumio[957]: info: Preload queue cleared Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/妈妈，我....flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/妈妈，我....flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/在希望的田野上.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/在希望的田野上.flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/那些花儿.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/那些花儿.flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/我去2000年.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/我去2000年.flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/旅途.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/旅途.flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/别，千万别.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/别，千万别.flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/白桦林.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/白桦林.flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/活着.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/活着.flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/召唤.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/召唤.flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/九月.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/九月.flac Oct 29 23:29:33 volumio volumio[957]: info: Adding Item to queue: music-library/NAS/music/music/我去2000年/火车开往冬天.flac Oct 29 23:29:33 volumio volumio[957]: info: Using cached record of: music-library/NAS/music/music/我去2000年/火车开往冬天.flac Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::stop Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::saveQueue Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::play index undefined Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/music/我去2000年/New Boy.flac Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand stop took 66 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand stop took 10 milliseconds Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:33 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 11 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand clear took 10 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 9 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 7 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 6 milliseconds Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/music/我去2000年/New Boy.flac" Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: 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} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: No code Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: 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} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: No code Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: 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} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: No code Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 140ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 140ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 139ms Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:33 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:33 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:33 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:33 volumio volumio[957]: error: updateQueue error: null Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:33 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 146ms Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand playlistinfo took 143 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand add "NAS/music/music/我去2000年/New Boy.flac" took 143 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 10ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 9ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 8ms Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play Oct 29 23:29:33 volumio volumio[957]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 162ms Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:33 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:33 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces system playlist update Oct 29 23:29:33 volumio volumio[957]: info: Ignoring MPD Status Update Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 39ms Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand play took 35 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 33ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 32ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 30ms Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 44 milliseconds Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 47 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 45 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 44 milliseconds Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: Oct 29 23:29:33 volumio volumio[957]: ---------------------------- MPD announces state update: player Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::getState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 14 milliseconds Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Boy","artist":"朴树","album":"我去2000年","uri":"NAS/music/music/我去2000年/New Boy.flac","trackType":"flac"} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 67ms Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 16 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 19 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand status took 18 milliseconds Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseState Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Boy","artist":"朴树","album":"我去2000年","uri":"NAS/music/music/我去2000年/New Boy.flac","trackType":"flac"} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:33 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Boy","artist":"朴树","album":"我去2000年","uri":"NAS/music/music/我去2000年/New Boy.flac","trackType":"flac"} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:33 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Boy","artist":"朴树","album":"我去2000年","uri":"NAS/music/music/我去2000年/New Boy.flac","trackType":"flac"} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:33 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Boy","artist":"朴树","album":"我去2000年","uri":"NAS/music/music/我去2000年/New Boy.flac","trackType":"flac"} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:33 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 208ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 207ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 207ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 167ms Oct 29 23:29:33 volumio volumio[957]: info: sendMpdCommand playlistinfo took 142 milliseconds Oct 29 23:29:33 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Boy","artist":"朴树","album":"我去2000年","uri":"NAS/music/music/我去2000年/New Boy.flac","trackType":"flac"} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:33 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Boy","artist":"朴树","album":"我去2000年","uri":"NAS/music/music/我去2000年/New Boy.flac","trackType":"flac"} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:33 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ControllerMpd::pushState Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::servicePushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"New Boy","artist":"朴树","album":"我去2000年","uri":"NAS/music/music/我去2000年/New Boy.flac","trackType":"flac"} Oct 29 23:29:33 volumio volumio[957]: verbose: CURRENT POSITION 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Oct 29 23:29:33 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: CoreStateMachine::pushState Oct 29 23:29:33 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Oct 29 23:29:33 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 288ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 287ms Oct 29 23:29:33 volumio volumio[957]: info: ------------------------------ 286ms Oct 29 23:29:46 volumio wpa_supplicant[859]: RRM: Ignoring radio measurement request: Not RRM network Oct 29 23:29:49 volumio wpa_supplicant[859]: RRM: Ignoring radio measurement request: Not RRM network Oct 29 23:29:50 volumio wpa_supplicant[859]: RRM: Ignoring radio measurement request: Not RRM network Oct 29 23:30:26 volumio volumio[957]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 23:30:26 volumio volumio[957]: Error: connect ETIMEDOUT 199.16.156.71:443 Oct 29 23:30:26 volumio volumio[957]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 29 23:30:26 volumio volumio[957]: errno: -110, Oct 29 23:30:26 volumio volumio[957]: code: 'ETIMEDOUT', Oct 29 23:30:26 volumio volumio[957]: syscall: 'connect', Oct 29 23:30:26 volumio volumio[957]: address: '199.16.156.71', Oct 29 23:30:26 volumio volumio[957]: port: 443 Oct 29 23:30:26 volumio volumio[957]: } Oct 29 23:30:26 volumio volumio[957]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 23:30:27 volumio sudo[9317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-29 23:29 Oct 29 23:30:27 volumio sudo[9317]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"