Oct 02 14:11:00 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:11:00 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:00 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 14:11:00 volumio volumio[7531]: info: MPD Database updated - AlbumList cache refreshed Oct 02 14:11:00 volumio volumio[7531]: info: Oct 02 14:11:00 volumio volumio[7531]: ---------------------------- MPD announces state update: database Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:00 volumio volumio[7531]: info: Oct 02 14:11:00 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:00 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:00 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:00 volumio volumio[7531]: info: MPD Database updated - AlbumList cache refreshed Oct 02 14:11:00 volumio volumio[7531]: info: Oct 02 14:11:00 volumio volumio[7531]: ---------------------------- MPD announces state update: database Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:00 volumio volumio[7531]: info: Oct 02 14:11:00 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:00 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:00 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:00 volumio volumio[7531]: info: MPD Database updated - AlbumList cache refreshed Oct 02 14:11:00 volumio volumio[7531]: info: Oct 02 14:11:00 volumio volumio[7531]: ---------------------------- MPD announces state update: database Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:00 volumio volumio[7531]: info: Oct 02 14:11:00 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:00 volumio volumio[7531]: info: sendMpdCommand status took 4867 milliseconds Oct 02 14:11:00 volumio volumio[7531]: info: sendMpdCommand status took 4867 milliseconds Oct 02 14:11:00 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:00 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:00 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 1347 milliseconds Oct 02 14:11:00 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 02 14:11:00 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:01 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14759,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:01 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:01 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14759,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:01 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:01 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: ------------------------------ 6229ms Oct 02 14:11:01 volumio volumio[7531]: info: ------------------------------ 6228ms Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 14:11:01 volumio volumio[7531]: info: [1790921461456] Database update started Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand status took 1424 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand status took 1425 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand status took 1420 milliseconds Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: info: Command Router : Notfying DB Updatefalse Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand status took 1463 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand status took 1463 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand status took 1462 milliseconds Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: info: Command Router : Notfying DB Updatefalse Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand status took 1496 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand status took 1495 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand status took 1495 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 173 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 54 milliseconds Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: info: Command Router : Notfying DB Updatefalse Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:01 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14759,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:01 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:01 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19572,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:01 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:01 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19572,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:01 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:01 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: ------------------------------ 6434ms Oct 02 14:11:01 volumio volumio[7531]: info: ------------------------------ 1575ms Oct 02 14:11:01 volumio volumio[7531]: info: ------------------------------ 1575ms Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 95 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 95 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 48 milliseconds Oct 02 14:11:01 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 48 milliseconds Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:01 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:01 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19664,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:01 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:01 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19664,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:01 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:01 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20963,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:01 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:01 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20963,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:01 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:01 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:01 volumio volumio[7531]: info: ------------------------------ 1654ms Oct 02 14:11:01 volumio volumio[7531]: info: ------------------------------ 1654ms Oct 02 14:11:01 volumio volumio[7531]: info: ------------------------------ 1653ms Oct 02 14:11:01 volumio volumio[7531]: info: ------------------------------ 1654ms Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 14:11:01 volumio volumio[7531]: info: Discovery: Getting this device information Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:01 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:01 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 14:11:05 volumio volumio[7531]: info: Retrieving Cloud Streaming UI Oct 02 14:11:05 volumio volumio[7531]: info: Getting Tidal Cloud Configuration Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 14:11:05 volumio volumio[7531]: info: Getting Qobuz Cloud Configuration Oct 02 14:11:05 volumio volumio[7531]: info: Asking plugin for UI Config Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 14:11:05 volumio volumio[7531]: info: Getting Spotify Cloud Configuration Oct 02 14:11:05 volumio volumio[7531]: info: Asking plugin for UI Config Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 14:11:05 volumio volumio[7531]: info: Saving Spotify Acccount Oct 02 14:11:05 volumio volumio[7531]: info: Got Tidal Cloud Configuration Oct 02 14:11:05 volumio volumio[7531]: info: Got it Oct 02 14:11:05 volumio volumio[7531]: info: Got it Oct 02 14:11:05 volumio volumio[7531]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 14:11:05 volumio volumio[7531]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 14:11:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand update Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:07 volumio volumio[7531]: info: Oct 02 14:11:07 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand update took 3 milliseconds Oct 02 14:11:07 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:07 volumio volumio[7531]: info: Oct 02 14:11:07 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:07 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:07 volumio volumio[7531]: info: Oct 02 14:11:07 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:07 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:07 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:07 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand status took 51 milliseconds Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand status took 51 milliseconds Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:07 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:07 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:07 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:07 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27168,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:07 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:07 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:07 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:07 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:07 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:07 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:07 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27168,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:07 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:07 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:07 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:07 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:07 volumio volumio[7531]: info: ------------------------------ 120ms Oct 02 14:11:07 volumio volumio[7531]: info: ------------------------------ 121ms Oct 02 14:11:07 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 02 14:11:07 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:07 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:07 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:07 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27168,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:07 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:07 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:07 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:07 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:07 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:07 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:07 volumio volumio[7531]: info: ------------------------------ 154ms Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:08 volumio volumio[7531]: info: Oct 02 14:11:08 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:08 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:08 volumio volumio[7531]: info: Oct 02 14:11:08 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:08 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:08 volumio volumio[7531]: info: Oct 02 14:11:08 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:08 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:08 volumio volumio[7531]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:11:08 volumio volumio[7531]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:11:08 volumio volumio[7531]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:11:08 volumio volumio[7531]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:08 volumio volumio[7531]: info: Command Router : Notfying DB Updatefalse Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:08 volumio volumio[7531]: info: Command Router : Notfying DB Updatefalse Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:08 volumio volumio[7531]: info: sendMpdCommand status took 58 milliseconds Oct 02 14:11:08 volumio volumio[7531]: info: sendMpdCommand status took 59 milliseconds Oct 02 14:11:08 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 02 14:11:08 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:08 volumio volumio[7531]: info: Command Router : Notfying DB Updatefalse Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:08 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:08 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:08 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27762,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:08 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:08 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:08 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:08 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:08 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:08 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:08 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27762,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:08 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:08 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:08 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:08 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:08 volumio volumio[7531]: info: ------------------------------ 111ms Oct 02 14:11:08 volumio volumio[7531]: info: ------------------------------ 110ms Oct 02 14:11:08 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 48 milliseconds Oct 02 14:11:08 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:08 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:08 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:08 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27762,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:08 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:08 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:08 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:08 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:08 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:08 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:08 volumio volumio[7531]: info: ------------------------------ 134ms Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand rescan Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:09 volumio volumio[7531]: info: Oct 02 14:11:09 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand rescan took 3 milliseconds Oct 02 14:11:09 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:09 volumio volumio[7531]: info: Oct 02 14:11:09 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:09 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:09 volumio volumio[7531]: info: Oct 02 14:11:09 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:09 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand status took 6 milliseconds Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand status took 4 milliseconds Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand status took 3 milliseconds Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand status took 3 milliseconds Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:09 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:09 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:09 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand status took 75 milliseconds Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 48 milliseconds Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 02 14:11:09 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:09 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:09 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:09 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:09 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29061,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:09 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:09 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:09 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:09 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:09 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:09 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:09 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29061,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:09 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:09 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:09 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:09 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:09 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:09 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:09 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29061,"duration":334,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"刀郎 - 十送红军【有间音乐】.wav","artist":null,"album":null,"uri":"NAS/wangjianli/歌曲/刀郎 - 十送红军【有间音乐】.wav","trackType":"wav"} Oct 02 14:11:09 volumio volumio[7531]: verbose: CURRENT POSITION 15 Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:09 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:09 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:09 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:09 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:09 volumio volumio[7531]: info: ------------------------------ 121ms Oct 02 14:11:09 volumio volumio[7531]: info: ------------------------------ 118ms Oct 02 14:11:09 volumio volumio[7531]: info: ------------------------------ 117ms Oct 02 14:11:09 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 14:11:12 volumio volumio[7531]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.188 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Oct 02 14:11:12 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:12 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:12 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 14:11:12 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 14:11:13 volumio volumio[7531]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.188 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:13 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 14:11:13 volumio volumio[7531]: info: Received Get System Info Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 14:11:13 volumio volumio[7531]: info: Discovery: Getting this device information Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:13 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:13 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:13 volumio volumio[7531]: info: Listing playlists Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::volumioGetQueue Oct 02 14:11:13 volumio volumio[7531]: info: CoreStateMachine::getQueue Oct 02 14:11:13 volumio volumio[7531]: info: CorePlayQueue::getQueue Oct 02 14:11:13 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 14:11:14 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 14:11:16 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 02 14:11:16 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand update Oct 02 14:11:16 volumio volumio[7531]: info: sendMpdCommand update took 2 milliseconds Oct 02 14:11:17 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 14:11:25 volumio volumio[7531]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.188 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Oct 02 14:11:25 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:25 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:25 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 14:11:25 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 14:11:26 volumio volumio[7531]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.188 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 14:11:26 volumio volumio[7531]: info: Received Get System Info Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 14:11:26 volumio volumio[7531]: info: Discovery: Getting this device information Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:26 volumio volumio[7531]: info: Listing playlists Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::volumioGetQueue Oct 02 14:11:26 volumio volumio[7531]: info: CoreStateMachine::getQueue Oct 02 14:11:26 volumio volumio[7531]: info: CorePlayQueue::getQueue Oct 02 14:11:26 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 14:11:28 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:11:28 volumio volumio[7531]: info: CURURI: artists:// Oct 02 14:11:28 volumio volumio[7531]: info: Preload queue cleared Oct 02 14:11:30 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:11:30 volumio volumio[7531]: info: CURURI: music-library Oct 02 14:11:30 volumio volumio[7531]: info: Preload queue cleared Oct 02 14:11:33 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:11:33 volumio volumio[7531]: info: CURURI: music-library/NAS Oct 02 14:11:33 volumio volumio[7531]: info: Preload queue cleared Oct 02 14:11:36 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:11:36 volumio volumio[7531]: info: CURURI: music-library/NAS/hau Oct 02 14:11:36 volumio volumio[7531]: error: Failed LSINFO: null Oct 02 14:11:36 volumio volumio[7531]: info: Preload queue cleared Oct 02 14:11:38 volumio volumio[7531]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%C8%BA%3F%3F/dfbb8b5f-9fe1-4744-84f7-0787f4e899e2.jpg' Oct 02 14:11:38 volumio volumio[7531]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.188 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Oct 02 14:11:38 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:38 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:38 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 14:11:38 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 14:11:39 volumio volumio[7531]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.188 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:39 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 14:11:39 volumio volumio[7531]: info: Received Get System Info Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 14:11:39 volumio volumio[7531]: info: Discovery: Getting this device information Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:39 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:11:39 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:39 volumio volumio[7531]: info: Listing playlists Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::volumioGetQueue Oct 02 14:11:39 volumio volumio[7531]: info: CoreStateMachine::getQueue Oct 02 14:11:39 volumio volumio[7531]: info: CorePlayQueue::getQueue Oct 02 14:11:39 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 14:11:40 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:11:40 volumio volumio[7531]: info: CURURI: music-library/NAS/wangjianli Oct 02 14:11:40 volumio volumio[7531]: info: Preload queue cleared Oct 02 14:11:40 volumio volumio[7531]: info: Preloading song: music-library/NAS/wangjianli/02.降央卓玛_卓玛.dts Oct 02 14:11:40 volumio volumio[7531]: info: Preloading song: music-library/NAS/wangjianli/那英-出賣.ape Oct 02 14:11:40 volumio volumio[7531]: info: Preloading song: music-library/NAS/wangjianli/10 聽見有人叫你宝貝.mp3 Oct 02 14:11:40 volumio volumio[7531]: info: Exploding uri music-library/NAS/wangjianli/02.降央卓玛_卓玛.dts in service mpd Oct 02 14:11:40 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=//extralarge&path=%2Fmnt%2FNAS%2Fwangjianli%2F02.%E9%99%8D%E5%A4%AE%E5%8D%93%E7%8E%9B_%E5%8D%93%E7%8E%9B.dts&metadata=false Oct 02 14:11:40 volumio volumio[7531]: info: URI /mnt/NAS/wangjianli/02.降央卓玛_卓玛.dts Oct 02 14:11:40 volumio volumio[7531]: info: Exploding uri music-library/NAS/wangjianli/那英-出賣.ape in service mpd Oct 02 14:11:40 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%5B51ape.com%5D%E9%82%A3%E8%8B%B1/%E5%85%A8%E7%BB%8F%E5%85%B8/extralarge&path=%2Fmnt%2FNAS%2Fwangjianli%2F%E9%82%A3%E8%8B%B1-%E5%87%BA%E8%B3%A3.ape&metadata=false Oct 02 14:11:40 volumio volumio[7531]: info: URI /mnt/NAS/wangjianli/那英-出賣.ape Oct 02 14:11:40 volumio volumio[7531]: info: Exploding uri music-library/NAS/wangjianli/10 聽見有人叫你宝貝.mp3 in service mpd Oct 02 14:11:40 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E6%9D%8E%E5%AE%97%E7%9B%9B/%E6%84%9B%E6%83%85%E8%AB%96%20CD1/extralarge&path=%2Fmnt%2FNAS%2Fwangjianli%2F10%20%E8%81%BD%E8%A6%8B%E6%9C%89%E4%BA%BA%E5%8F%AB%E4%BD%A0%E5%AE%9D%E8%B2%9D.mp3&metadata=false Oct 02 14:11:40 volumio volumio[7531]: info: URI /mnt/NAS/wangjianli/10 聽見有人叫你宝貝.mp3 Oct 02 14:11:42 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:11:42 volumio volumio[7531]: info: CURURI: music-library/NAS/hsjjs Oct 02 14:11:42 volumio volumio[7531]: info: Preload queue cleared Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/红梅 - 薛鹏.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/当爱在靠近.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/遇见- 孙燕姿.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/草原夜色美.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/囚鸟.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/雪人 - 范晓萱.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/如果云知道 - 许茹芸.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/独角戏.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/征服.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/梦一场.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/出卖.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/一笑而过.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/痴心绝对.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/秋来秋去 - 王晴.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/尘缘 - 罗文.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/最熟悉的陌生人 - 萧亚轩.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/春光美 - 常安.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/卓玛 - 降央卓玛.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/我和草原有个约定 - 降央卓玛.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/西海情歌 - 降央卓玛.flac Oct 02 14:11:42 volumio volumio[7531]: info: Preloading song: music-library/NAS/hsjjs/美丽的草原,我的家 - 德德玛.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/红梅 - 薛鹏.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E8%96%9B%E9%B9%8F/%E3%80%8A%E7%BA%A2%E6%A2%85%E3%80%8B/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E7%BA%A2%E6%A2%85%20-%20%E8%96%9B%E9%B9%8F.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/红梅 - 薛鹏.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/当爱在靠近.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E5%88%98%E8%8B%A5%E8%8B%B1/Love%20%26%20the%20City/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E5%BD%93%E7%88%B1%E5%9C%A8%E9%9D%A0%E8%BF%91.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/当爱在靠近.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/遇见- 孙燕姿.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E5%AD%99%E7%87%95%E5%A7%BF/The%20Moment/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E9%81%87%E8%A7%81-%20%E5%AD%99%E7%87%95%E5%A7%BF.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/遇见- 孙燕姿.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/草原夜色美.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E9%99%8D%E5%A4%AE%E5%8D%93%E7%8E%9B/%E4%B8%AD%E5%9B%BD%E4%B9%8B%E5%A3%B0/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E8%8D%89%E5%8E%9F%E5%A4%9C%E8%89%B2%E7%BE%8E.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/草原夜色美.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/囚鸟.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E5%BD%AD%E7%BE%9A/%E5%9B%9A%E9%B8%9F%20(Remastered)/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E5%9B%9A%E9%B8%9F.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/囚鸟.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/雪人 - 范晓萱.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E8%8C%83%E6%99%93%E8%90%B1/%E5%A5%BD%E6%83%B3%E8%B0%88%E6%81%8B%E7%88%B1/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E9%9B%AA%E4%BA%BA%20-%20%E8%8C%83%E6%99%93%E8%90%B1.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/雪人 - 范晓萱.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/如果云知道 - 许茹芸.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E8%AE%B8%E8%8C%B9%E8%8A%B8/%E5%A6%82%E6%9E%9C%E4%BA%91%E7%9F%A5%E9%81%93/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E5%A6%82%E6%9E%9C%E4%BA%91%E7%9F%A5%E9%81%93%20-%20%E8%AE%B8%E8%8C%B9%E8%8A%B8.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/如果云知道 - 许茹芸.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/独角戏.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E8%AE%B8%E8%8C%B9%E8%8A%B8/%E5%A6%82%E6%9E%9C%E4%BA%91%E7%9F%A5%E9%81%93/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E7%8B%AC%E8%A7%92%E6%88%8F.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/独角戏.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/征服.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E9%82%A3%E8%8B%B1/%E5%BE%81%E6%9C%8D/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E5%BE%81%E6%9C%8D.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/征服.flac Oct 02 14:11:42 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/梦一场.flac in service mpd Oct 02 14:11:42 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E9%82%A3%E8%8B%B1/%E5%BE%81%E6%9C%8D/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E6%A2%A6%E4%B8%80%E5%9C%BA.flac&metadata=false Oct 02 14:11:42 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/梦一场.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/出卖.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E9%82%A3%E8%8B%B1/%E5%BF%83%E9%85%B8%E7%9A%84%E6%B5%AA%E6%BC%AB/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E5%87%BA%E5%8D%96.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/出卖.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/一笑而过.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E9%82%A3%E8%8B%B1/%E6%88%91%E4%B8%8D%E6%98%AF%E5%A4%A9%E4%BD%BF/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E4%B8%80%E7%AC%91%E8%80%8C%E8%BF%87.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/一笑而过.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/痴心绝对.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E6%9D%8E%E5%9C%A3%E6%9D%B0/%E7%97%B4%E5%BF%83%E7%BB%9D%E5%AF%B9/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E7%97%B4%E5%BF%83%E7%BB%9D%E5%AF%B9.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/痴心绝对.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/秋来秋去 - 王晴.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E7%8E%8B%E6%99%B4/%E7%A7%8B%E6%9D%A5%E7%A7%8B%E5%8E%BB%EF%BC%88%E5%8F%88%E5%90%8D%EF%BC%9A%E7%A7%8B%E5%8E%BB%E7%A7%8B%E6%9D%A5%EF%BC%89/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E7%A7%8B%E6%9D%A5%E7%A7%8B%E5%8E%BB%20-%20%E7%8E%8B%E6%99%B4.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/秋来秋去 - 王晴.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/尘缘 - 罗文.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E7%BD%97%E6%96%87/%E7%BD%97%E6%96%8733%E9%A6%96%E6%8B%9B%E7%89%8C%E6%AD%8C/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E5%B0%98%E7%BC%98%20-%20%E7%BD%97%E6%96%87.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/尘缘 - 罗文.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/最熟悉的陌生人 - 萧亚轩.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E8%90%A7%E4%BA%9A%E8%BD%A9/%E8%90%A7%E4%BA%9A%E8%BD%A9%20%E5%90%8C%E5%90%8D%E4%B8%93%E8%BE%91/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E6%9C%80%E7%86%9F%E6%82%89%E7%9A%84%E9%99%8C%E7%94%9F%E4%BA%BA%20-%20%E8%90%A7%E4%BA%9A%E8%BD%A9.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/最熟悉的陌生人 - 萧亚轩.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/春光美 - 常安.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E5%B8%B8%E5%AE%89/%E8%BE%BE%E7%8E%9B%E8%8A%B1/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E6%98%A5%E5%85%89%E7%BE%8E%20-%20%E5%B8%B8%E5%AE%89.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/春光美 - 常安.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/卓玛 - 降央卓玛.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E9%99%8D%E5%A4%AE%E5%8D%93%E7%8E%9B/%E9%87%91%E8%89%B2%E7%9A%84%E5%91%BC%E5%94%A4/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E5%8D%93%E7%8E%9B%20-%20%E9%99%8D%E5%A4%AE%E5%8D%93%E7%8E%9B.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/卓玛 - 降央卓玛.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/我和草原有个约定 - 降央卓玛.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E9%99%8D%E5%A4%AE%E5%8D%93%E7%8E%9B/%E9%87%91%E8%89%B2%E7%9A%84%E5%91%BC%E5%94%A4/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E6%88%91%E5%92%8C%E8%8D%89%E5%8E%9F%E6%9C%89%E4%B8%AA%E7%BA%A6%E5%AE%9A%20-%20%E9%99%8D%E5%A4%AE%E5%8D%93%E7%8E%9B.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/我和草原有个约定 - 降央卓玛.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/西海情歌 - 降央卓玛.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E9%99%8D%E5%A4%AE%E5%8D%93%E7%8E%9B/%E9%87%91%E8%89%B2%E7%9A%84%E8%8D%89%E5%8E%9F/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E8%A5%BF%E6%B5%B7%E6%83%85%E6%AD%8C%20-%20%E9%99%8D%E5%A4%AE%E5%8D%93%E7%8E%9B.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/西海情歌 - 降央卓玛.flac Oct 02 14:11:43 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/美丽的草原,我的家 - 德德玛.flac in service mpd Oct 02 14:11:43 volumio volumio[7531]: info: ALBUMART /albumart?cacheid=730&web=%E5%BE%B7%E5%BE%B7%E7%8E%9B/%E9%9F%B3%E4%B9%90%E4%B8%AD%E5%9B%BD%C2%B7%E6%AD%8C%E6%9B%B2/extralarge&path=%2Fmnt%2FNAS%2Fhsjjs%2F%E7%BE%8E%E4%B8%BD%E7%9A%84%E8%8D%89%E5%8E%9F%2C%E6%88%91%E7%9A%84%E5%AE%B6%20-%20%E5%BE%B7%E5%BE%B7%E7%8E%9B.flac&metadata=false Oct 02 14:11:43 volumio volumio[7531]: info: URI /mnt/NAS/hsjjs/美丽的草原,我的家 - 德德玛.flac Oct 02 14:11:44 volumio volumio[7531]: info: Preload queue cleared Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::ClearQueue Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::stop Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::stPlaybackTimer Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::updateTrackBlock Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrackBlock Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::serviceStop Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 15 Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::serviceStop Oct 02 14:11:44 volumio volumio[7531]: info: ControllerMpd::stop Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::clearPlayQueue Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::saveQueue Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushQueue Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::addQueueItems Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::addQueueItems Oct 02 14:11:44 volumio volumio[7531]: info: Preload queue cleared Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/红梅 - 薛鹏.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/红梅 - 薛鹏.flac Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushQueue Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::saveQueue Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::updateTrackBlock Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrackBlock Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPlay Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::play index 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::addQueueItems Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::addQueueItems Oct 02 14:11:44 volumio volumio[7531]: info: Preload queue cleared Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/当爱在靠近.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/当爱在靠近.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/遇见- 孙燕姿.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/遇见- 孙燕姿.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/草原夜色美.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/草原夜色美.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/囚鸟.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/囚鸟.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/雪人 - 范晓萱.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/雪人 - 范晓萱.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/如果云知道 - 许茹芸.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/如果云知道 - 许茹芸.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/独角戏.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/独角戏.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/征服.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/征服.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/梦一场.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/梦一场.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/出卖.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/出卖.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/一笑而过.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/一笑而过.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/痴心绝对.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/痴心绝对.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/秋来秋去 - 王晴.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/秋来秋去 - 王晴.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/尘缘 - 罗文.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/尘缘 - 罗文.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/最熟悉的陌生人 - 萧亚轩.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/最熟悉的陌生人 - 萧亚轩.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/春光美 - 常安.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/春光美 - 常安.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/卓玛 - 降央卓玛.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/卓玛 - 降央卓玛.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/我和草原有个约定 - 降央卓玛.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/我和草原有个约定 - 降央卓玛.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/西海情歌 - 降央卓玛.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/西海情歌 - 降央卓玛.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/美丽的草原,我的家 - 德德玛.flac Oct 02 14:11:44 volumio volumio[7531]: info: Using cached record of: music-library/NAS/hsjjs/美丽的草原,我的家 - 德德玛.flac Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/DianNaoDian_v7.5_2609.zip Oct 02 14:11:44 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/DianNaoDian_v7.5_2609.zip in service mpd Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/Win7x64_2026.10_GHO.iso Oct 02 14:11:44 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/Win7x64_2026.10_GHO.iso in service mpd Oct 02 14:11:44 volumio volumio[7531]: info: Adding Item to queue: music-library/NAS/hsjjs/Volumio-4.119-2026-03-24-x86_amd64.zip Oct 02 14:11:44 volumio volumio[7531]: info: Exploding uri music-library/NAS/hsjjs/Volumio-4.119-2026-03-24-x86_amd64.zip in service mpd Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::stop Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::play index undefined Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::clearAddPlayTracks NAS/hsjjs/红梅 - 薛鹏.flac Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:11:44 volumio volumio[7531]: info: Oct 02 14:11:44 volumio volumio[7531]: ---------------------------- MPD announces state update: player Oct 02 14:11:44 volumio volumio[7531]: info: sendMpdCommand stop took 47 milliseconds Oct 02 14:11:44 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:44 volumio volumio[7531]: info: sendMpdCommand stop took 19 milliseconds Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushQueue Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::saveQueue Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::updateTrackBlock Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrackBlock Oct 02 14:11:44 volumio volumio[7531]: info: Oct 02 14:11:44 volumio volumio[7531]: ---------------------------- MPD announces state update: player Oct 02 14:11:44 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:44 volumio volumio[7531]: info: Oct 02 14:11:44 volumio volumio[7531]: ---------------------------- MPD announces state update: player Oct 02 14:11:44 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:44 volumio volumio[7531]: info: Oct 02 14:11:44 volumio volumio[7531]: ---------------------------- MPD announces system playlist update Oct 02 14:11:44 volumio volumio[7531]: info: Ignoring MPD Status Update Oct 02 14:11:44 volumio volumio[7531]: info: sendMpdCommand status took 37 milliseconds Oct 02 14:11:44 volumio volumio[7531]: info: sendMpdCommand clear took 31 milliseconds Oct 02 14:11:44 volumio volumio[7531]: info: sendMpdCommand status took 6 milliseconds Oct 02 14:11:44 volumio volumio[7531]: info: sendMpdCommand status took 5 milliseconds Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand add "NAS/hsjjs/红梅 - 薛鹏.flac" Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:44 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 02 14:11:44 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::syncState stateService stop Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:44 volumio volumio[7531]: info: No code Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:44 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 02 14:11:44 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::syncState stateService stop Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:44 volumio volumio[7531]: info: No code Oct 02 14:11:44 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:44 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:44 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:44 volumio volumio[7531]: info: ------------------------------ 65ms Oct 02 14:11:44 volumio volumio[7531]: info: ------------------------------ 65ms Oct 02 14:11:44 volumio volumio[7531]: info: Oct 02 14:11:44 volumio volumio[7531]: ---------------------------- MPD announces system playlist update Oct 02 14:11:44 volumio volumio[7531]: info: Ignoring MPD Status Update Oct 02 14:11:44 volumio volumio[7531]: info: Oct 02 14:11:44 volumio volumio[7531]: ---------------------------- MPD announces system playlist update Oct 02 14:11:44 volumio volumio[7531]: info: Ignoring MPD Status Update Oct 02 14:11:44 volumio volumio[7531]: error: updateQueue error: null Oct 02 14:11:44 volumio volumio[7531]: info: Oct 02 14:11:44 volumio volumio[7531]: ---------------------------- MPD announces system playlist update Oct 02 14:11:44 volumio volumio[7531]: info: Ignoring MPD Status Update Oct 02 14:11:44 volumio volumio[7531]: info: ------------------------------ 79ms Oct 02 14:11:44 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 76 milliseconds Oct 02 14:11:44 volumio volumio[7531]: info: sendMpdCommand add "NAS/hsjjs/红梅 - 薛鹏.flac" took 76 milliseconds Oct 02 14:11:44 volumio volumio[7531]: info: ------------------------------ 17ms Oct 02 14:11:44 volumio volumio[7531]: info: ------------------------------ 16ms Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:44 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand play Oct 02 14:11:44 volumio volumio[7531]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 14:11:44 volumio volumio[7531]: info: ------------------------------ 124ms Oct 02 14:11:44 volumio volumio[7531]: info: Oct 02 14:11:44 volumio volumio[7531]: ---------------------------- MPD announces system playlist update Oct 02 14:11:44 volumio volumio[7531]: info: Ignoring MPD Status Update Oct 02 14:11:44 volumio volumio[7531]: info: Oct 02 14:11:44 volumio volumio[7531]: ---------------------------- MPD announces system playlist update Oct 02 14:11:44 volumio volumio[7531]: info: Ignoring MPD Status Update Oct 02 14:11:44 volumio volumio[7531]: info: ------------------------------ 10ms Oct 02 14:11:44 volumio volumio[7531]: info: sendMpdCommand play took 9 milliseconds Oct 02 14:11:44 volumio volumio[7531]: info: ------------------------------ 7ms Oct 02 14:11:44 volumio volumio[7531]: info: ------------------------------ 6ms Oct 02 14:11:46 volumio volumio[7531]: info: Oct 02 14:11:46 volumio volumio[7531]: ---------------------------- MPD announces state update: player Oct 02 14:11:46 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:46 volumio volumio[7531]: info: Oct 02 14:11:46 volumio volumio[7531]: ---------------------------- MPD announces state update: player Oct 02 14:11:46 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:46 volumio volumio[7531]: info: Oct 02 14:11:46 volumio volumio[7531]: ---------------------------- MPD announces state update: player Oct 02 14:11:46 volumio volumio[7531]: info: Oct 02 14:11:46 volumio volumio[7531]: ---------------------------- MPD announces state update: player Oct 02 14:11:46 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:46 volumio volumio[7531]: info: sendMpdCommand status took 3 milliseconds Oct 02 14:11:46 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:46 volumio volumio[7531]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:46 volumio volumio[7531]: info: Oct 02 14:11:46 volumio volumio[7531]: ---------------------------- MPD announces state update: player Oct 02 14:11:46 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:46 volumio volumio[7531]: info: Oct 02 14:11:46 volumio volumio[7531]: ---------------------------- MPD announces state update: player Oct 02 14:11:46 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:46 volumio volumio[7531]: info: sendMpdCommand status took 68 milliseconds Oct 02 14:11:46 volumio volumio[7531]: info: sendMpdCommand status took 68 milliseconds Oct 02 14:11:46 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 02 14:11:46 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 63 milliseconds Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:46 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:46 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:46 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:46 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:46 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:11:46 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:11:46 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:46 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:11:46 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:46 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:46 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:46 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:11:46 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:11:46 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:46 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:46 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:46 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:46 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:46 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:11:46 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:47 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:47 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:47 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:47 volumio volumio[7531]: info: ------------------------------ 81ms Oct 02 14:11:47 volumio volumio[7531]: info: ------------------------------ 87ms Oct 02 14:11:47 volumio volumio[7531]: info: sendMpdCommand status took 25 milliseconds Oct 02 14:11:47 volumio volumio[7531]: info: sendMpdCommand status took 25 milliseconds Oct 02 14:11:47 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 02 14:11:47 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 02 14:11:47 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:47 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:47 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:11:47 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:11:47 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:47 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:47 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:47 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:47 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:47 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:11:47 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:11:47 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:47 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:47 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:47 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:47 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:47 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:47 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:47 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:47 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:47 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:47 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:47 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:47 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:11:47 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:11:47 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:47 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:47 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:47 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:47 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:47 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:47 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:47 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:47 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:47 volumio volumio[7531]: info: ------------------------------ 112ms Oct 02 14:11:47 volumio volumio[7531]: info: ------------------------------ 112ms Oct 02 14:11:51 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 4606 milliseconds Oct 02 14:11:51 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 4606 milliseconds Oct 02 14:11:51 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:51 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:11:51 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:51 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:51 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:51 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:11:51 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:11:51 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:51 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:51 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:51 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:51 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:51 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:51 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:51 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:51 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:51 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:11:51 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:11:51 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:51 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:11:51 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:11:51 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:11:51 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:11:51 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:11:51 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:51 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:51 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:51 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:11:51 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:11:51 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:11:51 volumio volumio[7531]: info: ------------------------------ 4720ms Oct 02 14:11:51 volumio volumio[7531]: info: ------------------------------ 4717ms Oct 02 14:11:56 volumio volumio[7531]: info: MPD Database updated - AlbumList cache refreshed Oct 02 14:11:56 volumio volumio[7531]: info: Oct 02 14:11:56 volumio volumio[7531]: ---------------------------- MPD announces state update: database Oct 02 14:11:56 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:56 volumio volumio[7531]: info: Oct 02 14:11:56 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:56 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:56 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:56 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:56 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:57 volumio volumio[7531]: info: MPD Database updated - AlbumList cache refreshed Oct 02 14:11:57 volumio volumio[7531]: info: Oct 02 14:11:57 volumio volumio[7531]: ---------------------------- MPD announces state update: database Oct 02 14:11:57 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:57 volumio volumio[7531]: info: Oct 02 14:11:57 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:57 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:57 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:57 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:57 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:57 volumio volumio[7531]: info: MPD Database updated - AlbumList cache refreshed Oct 02 14:11:57 volumio volumio[7531]: info: Oct 02 14:11:57 volumio volumio[7531]: ---------------------------- MPD announces state update: database Oct 02 14:11:57 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:57 volumio volumio[7531]: info: Oct 02 14:11:57 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:11:57 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:57 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:57 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:11:57 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:11:57 volumio volumio[7531]: info: sendMpdCommand status took 53 milliseconds Oct 02 14:11:57 volumio volumio[7531]: info: sendMpdCommand status took 53 milliseconds Oct 02 14:11:57 volumio volumio[7531]: info: sendMpdCommand status took 46 milliseconds Oct 02 14:11:57 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:02 volumio startx[1232]: (EE) client bug: timer event6 debounce: scheduled expiry is in the past (-299ms), your system is too slow Oct 02 14:12:02 volumio startx[1232]: (EE) client bug: timer event6 debounce short: scheduled expiry is in the past (-312ms), your system is too slow Oct 02 14:12:02 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand status took 5331 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand status took 5329 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand status took 5330 milliseconds Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:02 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:12:02 volumio volumio[7531]: info: Oct 02 14:12:02 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand status took 5457 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand status took 5456 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand status took 5456 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 157 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 157 milliseconds Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:02 volumio volumio[7531]: info: Command Router : Notfying DB Updatetrue Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:12:02 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:12:02 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:02 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6235,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:12:02 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:12:02 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:12:02 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:12:02 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:12:02 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:02 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:02 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:02 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:02 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:12:02 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:02 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6235,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:12:02 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:12:02 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:12:02 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:12:02 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:12:02 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:02 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:02 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:02 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:02 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:02 volumio volumio[7531]: info: ------------------------------ 5744ms Oct 02 14:12:02 volumio volumio[7531]: info: ------------------------------ 5745ms Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 306 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 305 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand status took 278 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand status took 273 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 02 14:12:02 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:12:02 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:05 volumio volumio[7531]: info: Command Router : Notfying DB Updatefalse Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:12:05 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6235,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:12:05 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:12:05 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6235,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:12:05 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:12:05 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6235,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:12:05 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:12:05 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6235,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:12:05 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:12:05 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: ------------------------------ 8435ms Oct 02 14:12:05 volumio volumio[7531]: info: ------------------------------ 8435ms Oct 02 14:12:05 volumio volumio[7531]: info: ------------------------------ 8449ms Oct 02 14:12:05 volumio volumio[7531]: info: ------------------------------ 8449ms Oct 02 14:12:05 volumio volumio[7531]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.188 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Oct 02 14:12:05 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 88 milliseconds Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:12:05 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11988,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1569 Kbps","isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:12:05 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:12:05 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:05 volumio volumio[7531]: info: ------------------------------ 3032ms Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:12:05 volumio volumio[7531]: info: Oct 02 14:12:05 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:12:05 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:12:05 volumio volumio[7531]: info: Oct 02 14:12:05 volumio volumio[7531]: ---------------------------- MPD announces state update: update Oct 02 14:12:05 volumio volumio[7531]: info: ControllerMpd::getState Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:12:05 volumio volumio[7531]: info: CoreCommandRouter::volumioGetQueue Oct 02 14:12:05 volumio volumio[7531]: info: CoreStateMachine::getQueue Oct 02 14:12:05 volumio volumio[7531]: info: CorePlayQueue::getQueue Oct 02 14:12:05 volumio volumio[7531]: info: sendMpdCommand status took 30 milliseconds Oct 02 14:12:05 volumio volumio[7531]: info: sendMpdCommand status took 29 milliseconds Oct 02 14:12:05 volumio volumio[7531]: info: sendMpdCommand status took 29 milliseconds Oct 02 14:12:05 volumio volumio[7531]: info: sendMpdCommand status took 29 milliseconds Oct 02 14:12:05 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:17 volumio volumio[7531]: info: Command Router : Notfying DB Updatefalse Oct 02 14:12:17 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:12:17 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:17 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:12:17 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:26 volumio volumio[7531]: info: Command Router : Notfying DB Updatefalse Oct 02 14:12:26 volumio volumio[7531]: info: CoreCommandRouter::Close All Modals sent Oct 02 14:12:26 volumio volumio[7531]: verbose: ControllerMpd::parseState Oct 02 14:12:26 volumio volumio[7531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:12:26 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 9241 milliseconds Oct 02 14:12:26 volumio volumio[7531]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 02 14:12:26 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:12:26 volumio volumio[7531]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:12:26 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:12:26 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:12:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:26 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14733,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1559 Kbps","isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:12:26 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:12:26 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:12:26 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:12:26 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:12:26 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:26 volumio volumio[7531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:12:26 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:26 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:26 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:26 volumio volumio[7531]: info: ControllerMpd::pushState Oct 02 14:12:26 volumio volumio[7531]: info: CoreCommandRouter::servicePushState Oct 02 14:12:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:26 volumio volumio[7531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14733,"duration":274,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1559 Kbps","isStreaming":false,"title":"红梅","artist":"薛鹏","album":"《红梅》","uri":"NAS/hsjjs/红梅 - 薛鹏.flac","trackType":"flac"} Oct 02 14:12:26 volumio volumio[7531]: verbose: CURRENT POSITION 0 Oct 02 14:12:26 volumio volumio[7531]: info: CoreStateMachine::syncState stateService play Oct 02 14:12:26 volumio volumio[7531]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:12:26 volumio volumio[7531]: info: Received an update from plugin. extracting info from payload Oct 02 14:12:26 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:26 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:26 volumio volumio[7531]: info: CoreStateMachine::pushState Oct 02 14:12:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:26 volumio volumio[7531]: info: CoreCommandRouter::volumioPushState Oct 02 14:12:26 volumio volumio[7531]: info: ------------------------------ 21143ms Oct 02 14:12:26 volumio volumio[7531]: info: ------------------------------ 21142ms Oct 02 14:12:26 volumio volumio[7531]: info: CoreCommandRouter::volumioGetState Oct 02 14:12:26 volumio volumio[7531]: info: CorePlayQueue::getTrack 0 Oct 02 14:12:26 volumio volumio[7531]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 14:12:26 volumio volumio[7531]: Error: socket hang up Oct 02 14:12:26 volumio volumio[7531]: at connResetException (node:internal/errors:720:14) Oct 02 14:12:26 volumio volumio[7531]: at TLSSocket.socketOnEnd (node:_http_client:519:23) Oct 02 14:12:26 volumio volumio[7531]: at TLSSocket.emit (node:events:526:35) Oct 02 14:12:26 volumio volumio[7531]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 02 14:12:26 volumio volumio[7531]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 02 14:12:26 volumio volumio[7531]: code: 'ECONNRESET' Oct 02 14:12:26 volumio volumio[7531]: } Oct 02 14:12:26 volumio volumio[7531]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 14:12:26 volumio sudo[8677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 14:11' Oct 02 14:12:26 volumio sudo[8677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"