Oct 12 15:01:19 volumio volumio[1001]: info: MPD Database updated - AlbumList cache refreshed Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: database Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: update Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: MPD Database updated - AlbumList cache refreshed Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: database Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: update Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: MPD Database updated - AlbumList cache refreshed Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: database Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: update Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: MPD Database updated - AlbumList cache refreshed Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: database Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: update Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: MPD Database updated - AlbumList cache refreshed Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: database Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: update Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: MPD Database updated - AlbumList cache refreshed Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: database Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: Oct 12 15:01:19 volumio volumio[1001]: ---------------------------- MPD announces state update: update Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:19 volumio volumio[1001]: Cannot compose Albumart path Oct 12 15:01:19 volumio volumio[1001]: info: sendMpdCommand status took 77 milliseconds Oct 12 15:01:19 volumio volumio[1001]: info: sendMpdCommand status took 80 milliseconds Oct 12 15:01:19 volumio volumio[1001]: info: sendMpdCommand status took 80 milliseconds Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: info: Command Router : Notfying DB Updatefalse Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: Cannot compose Albumart path Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 218 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 218 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 218 milliseconds Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: info: Command Router : Notfying DB Updatefalse Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: Cannot compose Albumart path Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 294 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 291 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 292 milliseconds Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: info: Command Router : Notfying DB Updatefalse Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: Cannot compose Albumart path Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 352 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 352 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 352 milliseconds Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: info: Command Router : Notfying DB Updatefalse Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: Cannot compose Albumart path Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 412 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 411 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 410 milliseconds Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: info: Command Router : Notfying DB Updatefalse Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: Cannot compose Albumart path Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 471 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 469 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand status took 470 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 311 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 310 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 251 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 250 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 174 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 173 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 103 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 103 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 12 15:01:20 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: info: Command Router : Notfying DB Updatefalse Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242608,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242608,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242608,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242608,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242608,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242608,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242608,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242608,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242708,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242708,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:20 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:20 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 850ms Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 849ms Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 848ms Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 848ms Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 848ms Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 850ms Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 843ms Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 844ms Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 839ms Oct 12 15:01:20 volumio volumio[1001]: info: ------------------------------ 838ms Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:20 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:21 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 565 milliseconds Oct 12 15:01:21 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 565 milliseconds Oct 12 15:01:21 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:21 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:21 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:21 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:21 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:21 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242708,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:21 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:21 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:21 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:21 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:21 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:21 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:21 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:21 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:21 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:21 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:21 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:21 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:21 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:21 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242708,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:21 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:21 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:21 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:21 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:21 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:21 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:21 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:21 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:21 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:21 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:21 volumio volumio[1001]: info: ------------------------------ 1130ms Oct 12 15:01:21 volumio volumio[1001]: info: ------------------------------ 1130ms Oct 12 15:01:21 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:21 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:21 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:21 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 71 Oct 12 15:01:57 volumio volumio[1001]: info: Prefetching next song Oct 12 15:01:57 volumio volumio[1001]: info: DOING PREFETCH IN MPD Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav" Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand add "INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav" took 2 milliseconds Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:01:57 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:01:57 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:01:57 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:01:57 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:01:57 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:01:57 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces state update: options Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand consume 1 took 16 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 16ms Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 14ms Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 13ms Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 13ms Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 14ms Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces state update: options Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces state update: options Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces state update: options Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces state update: options Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 22ms Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand status took 14 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand status took 7 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand status took 5 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand status took 3 milliseconds Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:57 volumio volumio[1001]: info: Oct 12 15:01:57 volumio volumio[1001]: ---------------------------- MPD announces state update: options Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand status took 14 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand status took 3 milliseconds Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280008,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:57 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:57 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280008,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:57 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:57 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280008,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:57 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:57 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280008,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:57 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:57 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 252ms Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 242ms Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 242ms Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 240ms Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 224 milliseconds Oct 12 15:01:57 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 218 milliseconds Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:57 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280008,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:57 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:57 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280108,"duration":284,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"Walala","artist":"Bheki Nqoko","album":"Kuwe","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 Walala.wav","trackType":"wav"} Oct 12 15:01:57 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:01:57 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:01:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:01:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 348ms Oct 12 15:01:57 volumio volumio[1001]: info: ------------------------------ 335ms Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:01:57 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:00 volumio volumio[1001]: info: Oct 12 15:02:00 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:02:00 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:02:00 volumio volumio[1001]: info: Oct 12 15:02:00 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:00 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:02:01 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:02:01 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:02:01 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:02:01 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Oct 12 15:02:01 volumio volumio[1001]: info: Ignoring MPD Status Update Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 37ms Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 36 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 30ms Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 29 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 31ms Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 28 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 28ms Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 20 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 20ms Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 45 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 39ms Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 33 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 32 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 17 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 14 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 13 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 10 milliseconds Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 225ms Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 219ms Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 216ms Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 214ms Oct 12 15:02:01 volumio volumio[1001]: info: Oct 12 15:02:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::getState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 154 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 155 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 154 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 152 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 152 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 153 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 145 milliseconds Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand status took 5 milliseconds Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 457ms Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 452ms Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 452ms Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 435ms Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 426ms Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 426ms Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 425ms Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 456 milliseconds Oct 12 15:02:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:02:01 volumio volumio[1001]: info: ControllerMpd::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":277,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9216 Kbps","isStreaming":false,"title":"关于我爱你","artist":"张悬","album":"城市","uri":"INTERNAL/music/24 赵鹏 WAV 146首/赵鹏 我爱你.wav","trackType":"wav"} Oct 12 15:02:01 volumio volumio[1001]: verbose: CURRENT POSITION 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 12 15:02:01 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 12 15:02:01 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:01 volumio volumio[1001]: info: ------------------------------ 582ms Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:01 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Oct 12 15:02:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 71 Oct 12 15:02:02 volumio volumio[1001]: info: CoreStateMachine::pushState Oct 12 15:02:02 volumio volumio[1001]: info: CorePlayQueue::getTrack 71 Oct 12 15:02:02 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:02:02 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 12 15:02:02 volumio volumio[1001]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Oct 12 15:02:02 volumio volumio[1001]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%82%AC/%E5%9F%8E%E5%B8%82/5f6c4163-5f99-4ba1-918b-3a9fa81fd17c.jpg' Oct 12 15:02:02 volumio volumio[1001]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%82%AC/%E5%9F%8E%E5%B8%82/5f6c4163-5f99-4ba1-918b-3a9fa81fd17c.jpg' Oct 12 15:02:08 volumio volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 7 Oct 12 15:02:08 volumio volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 7 Oct 12 15:02:08 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 12 15:02:08 volumio volumio[1001]: info: CorePlayQueue::getTrack 71 Oct 12 15:02:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 15:02:08 volumio volumio[1001]: info: CoreCommandRouter::volumioGetQueue Oct 12 15:02:08 volumio volumio[1001]: info: CoreStateMachine::getQueue Oct 12 15:02:08 volumio volumio[1001]: info: CorePlayQueue::getQueue Oct 12 15:02:08 volumio volumio[1001]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%82%AC/%E5%9F%8E%E5%B8%82/5f6c4163-5f99-4ba1-918b-3a9fa81fd17c.jpg' Oct 12 15:02:37 volumio sudo[4019]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 15:02:37 volumio sudo[4021]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 15:02:37 volumio sudo[4021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 15:02:37 volumio sudo[4019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 15:02:37 volumio sudo[4019]: pam_unix(sudo:session): session closed for user root Oct 12 15:02:37 volumio sudo[4021]: pam_unix(sudo:session): session closed for user root Oct 12 15:02:37 volumio volumio[1001]: verbose: New Socket.io Connection to 192.168.31.19 from 192.168.31.94 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 12 15:02:37 volumio sudo[4027]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 15:02:37 volumio sudo[4027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 15:02:37 volumio sudo[4029]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 15:02:37 volumio sudo[4029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 15:02:37 volumio sudo[4027]: pam_unix(sudo:session): session closed for user root Oct 12 15:02:37 volumio sudo[4029]: pam_unix(sudo:session): session closed for user root Oct 12 15:02:37 volumio volumio[1001]: verbose: New Socket.io Connection to 192.168.31.19 from 192.168.31.94 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 12 15:02:37 volumio volumio[1001]: info: CorePlayQueue::getTrack 71 Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::volumioGetQueue Oct 12 15:02:37 volumio volumio[1001]: info: CoreStateMachine::getQueue Oct 12 15:02:37 volumio volumio[1001]: info: CorePlayQueue::getQueue Oct 12 15:02:37 volumio volumio[1001]: info: Listing playlists Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 15:02:37 volumio volumio[1001]: info: Received Get System Info Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 15:02:37 volumio volumio[1001]: info: Discovery: Getting this device information Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 12 15:02:37 volumio volumio[1001]: info: CorePlayQueue::getTrack 71 Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 12 15:02:37 volumio volumio[1001]: info: CorePlayQueue::getTrack 71 Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 15:02:37 volumio volumio[1001]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%82%AC/%E5%9F%8E%E5%B8%82/5f6c4163-5f99-4ba1-918b-3a9fa81fd17c.jpg' Oct 12 15:02:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 15:02:39 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 12 15:02:39 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 15:02:39 volumio volumio[1001]: info: Received Get System Info Oct 12 15:02:39 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 15:02:39 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 15:02:39 volumio volumio[1001]: info: Discovery: Getting this device information Oct 12 15:02:39 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 12 15:02:39 volumio volumio[1001]: info: CorePlayQueue::getTrack 71 Oct 12 15:02:39 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 15:02:47 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 12 15:02:49 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 15:02:49 volumio volumio[1001]: info: CURURI: music-library Oct 12 15:02:49 volumio volumio[1001]: info: Preload queue cleared Oct 12 15:02:52 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 12 15:02:52 volumio volumio[1001]: info: CorePlayQueue::getTrack 71 Oct 12 15:02:52 volumio volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 15:02:52 volumio volumio[1001]: Error: connect ETIMEDOUT 173.231.12.107:443 Oct 12 15:02:52 volumio volumio[1001]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 12 15:02:52 volumio volumio[1001]: errno: -110, Oct 12 15:02:52 volumio volumio[1001]: code: 'ETIMEDOUT', Oct 12 15:02:52 volumio volumio[1001]: syscall: 'connect', Oct 12 15:02:52 volumio volumio[1001]: address: '173.231.12.107', Oct 12 15:02:52 volumio volumio[1001]: port: 443 Oct 12 15:02:52 volumio volumio[1001]: } Oct 12 15:02:52 volumio volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 15:02:53 volumio sudo[4123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 15:01' Oct 12 15:02:53 volumio sudo[4123]: 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"