-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-11-30 10:51:53 CET. -- Nov 30 10:50:02 volumio volumio[880]: info: Nov 30 10:50:02 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:02 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:02 volumio volumio[880]: info: Nov 30 10:50:02 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:02 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:02 volumio volumio[880]: info: Nov 30 10:50:02 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:02 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:02 volumio volumio[880]: info: Nov 30 10:50:02 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:02 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:02 volumio volumio[880]: info: Nov 30 10:50:02 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:02 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:02 volumio volumio[880]: info: Nov 30 10:50:02 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:02 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:02 volumio volumio[880]: info: Nov 30 10:50:02 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:02 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:02 volumio volumio[880]: info: Nov 30 10:50:02 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:02 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:02 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:50:02 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:50:02 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:50:02 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:50:02 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:50:02 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:02 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:50:02 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:02 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:02 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:02 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:02 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:02 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:02 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:02 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":221211,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:02 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:02 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:02 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:02 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":221211,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:02 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:02 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:02 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:02 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":221211,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:02 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:02 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:02 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:02 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":221211,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:02 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:02 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:02 volumio volumio[880]: info: ------------------------------ 97ms Nov 30 10:50:02 volumio volumio[880]: info: ------------------------------ 97ms Nov 30 10:50:02 volumio volumio[880]: info: ------------------------------ 95ms Nov 30 10:50:02 volumio volumio[880]: info: ------------------------------ 93ms Nov 30 10:50:06 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:50:08 volumio volumio[880]: info: Nov 30 10:50:08 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:08 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:08 volumio volumio[880]: info: Nov 30 10:50:08 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:08 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:08 volumio volumio[880]: info: Nov 30 10:50:08 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:08 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:08 volumio volumio[880]: info: Nov 30 10:50:08 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:08 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:08 volumio volumio[880]: info: Nov 30 10:50:08 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:08 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:08 volumio volumio[880]: info: Nov 30 10:50:08 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:08 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:08 volumio volumio[880]: info: Nov 30 10:50:08 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:08 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:08 volumio volumio[880]: info: Nov 30 10:50:08 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:08 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:08 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:50:08 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:50:08 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:50:08 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:50:08 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:50:08 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:08 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:50:08 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:08 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:08 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:08 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:08 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:08 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:08 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:08 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227335,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:08 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:08 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:08 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:08 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227335,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:08 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:08 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:08 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:08 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227335,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:08 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:08 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:08 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:08 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227335,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:08 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:08 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:08 volumio volumio[880]: info: ------------------------------ 86ms Nov 30 10:50:08 volumio volumio[880]: info: ------------------------------ 85ms Nov 30 10:50:08 volumio volumio[880]: info: ------------------------------ 83ms Nov 30 10:50:08 volumio volumio[880]: info: ------------------------------ 81ms Nov 30 10:50:15 volumio volumio[880]: info: Nov 30 10:50:15 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:15 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:15 volumio volumio[880]: info: Nov 30 10:50:15 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:15 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:15 volumio volumio[880]: info: Nov 30 10:50:15 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:15 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:15 volumio volumio[880]: info: Nov 30 10:50:15 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:15 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:15 volumio volumio[880]: info: Nov 30 10:50:15 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:15 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:15 volumio volumio[880]: info: Nov 30 10:50:15 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:15 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:15 volumio volumio[880]: info: Nov 30 10:50:15 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:15 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:15 volumio volumio[880]: info: Nov 30 10:50:15 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:15 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:15 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:50:15 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:15 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:15 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:15 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:50:15 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:50:15 volumio volumio[880]: info: ------------------------------ 4ms Nov 30 10:50:15 volumio volumio[880]: info: sendMpdCommand status took 4 milliseconds Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:15 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:15 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:50:15 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:15 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:15 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:15 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:15 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:15 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:15 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:15 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:15 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:15 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:15 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:15 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:15 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:15 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:15 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:15 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:15 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:15 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:15 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:15 volumio volumio[880]: info: ------------------------------ 84ms Nov 30 10:50:15 volumio volumio[880]: info: ------------------------------ 83ms Nov 30 10:50:15 volumio volumio[880]: info: ------------------------------ 81ms Nov 30 10:50:15 volumio volumio[880]: info: ------------------------------ 81ms Nov 30 10:50:16 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:50:16 volumio volumio[880]: info: Listing playlists Nov 30 10:50:16 volumio volumio[880]: info: Listing playlists Nov 30 10:50:19 volumio volumio[880]: info: Nov 30 10:50:19 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:19 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:19 volumio volumio[880]: info: Nov 30 10:50:19 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:19 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:19 volumio volumio[880]: info: Nov 30 10:50:19 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:19 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:19 volumio volumio[880]: info: Nov 30 10:50:19 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:19 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:19 volumio volumio[880]: info: Nov 30 10:50:19 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:19 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:19 volumio volumio[880]: info: Nov 30 10:50:19 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:19 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:19 volumio volumio[880]: info: Nov 30 10:50:19 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:19 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:19 volumio volumio[880]: info: Nov 30 10:50:19 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:19 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:19 volumio volumio[880]: info: ------------------------------ 14ms Nov 30 10:50:19 volumio volumio[880]: info: sendMpdCommand status took 13 milliseconds Nov 30 10:50:19 volumio volumio[880]: info: ------------------------------ 13ms Nov 30 10:50:19 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:50:19 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:50:19 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:50:19 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:19 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:19 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:19 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:19 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:19 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:19 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:19 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:19 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":238706,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:19 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:19 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:19 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:19 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":238706,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:19 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:19 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:19 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:19 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":238706,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:19 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:19 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:19 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:19 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":238706,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:19 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:19 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:19 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:19 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:19 volumio volumio[880]: info: ------------------------------ 105ms Nov 30 10:50:19 volumio volumio[880]: info: ------------------------------ 103ms Nov 30 10:50:19 volumio volumio[880]: info: ------------------------------ 99ms Nov 30 10:50:19 volumio volumio[880]: info: ------------------------------ 98ms Nov 30 10:50:21 volumio volumio[880]: info: Nov 30 10:50:21 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:21 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:21 volumio volumio[880]: info: Nov 30 10:50:21 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:21 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:21 volumio volumio[880]: info: Nov 30 10:50:21 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:21 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:21 volumio volumio[880]: info: Nov 30 10:50:21 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:21 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:21 volumio volumio[880]: info: Nov 30 10:50:21 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:21 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:21 volumio volumio[880]: info: Nov 30 10:50:21 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:21 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:21 volumio volumio[880]: info: Nov 30 10:50:21 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:21 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:21 volumio volumio[880]: info: Nov 30 10:50:21 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:21 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:21 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:50:21 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:50:21 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:50:21 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:50:21 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:50:21 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:21 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:50:21 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:21 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:50:21 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:50:21 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:21 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:21 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:21 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:21 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240088,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:21 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:21 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:21 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:21 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240088,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:21 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:21 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:21 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:21 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240088,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:21 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:21 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:21 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:21 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240088,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:21 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:21 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:21 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:21 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:21 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:21 volumio volumio[880]: info: ------------------------------ 82ms Nov 30 10:50:21 volumio volumio[880]: info: ------------------------------ 84ms Nov 30 10:50:21 volumio volumio[880]: info: ------------------------------ 83ms Nov 30 10:50:21 volumio volumio[880]: info: ------------------------------ 82ms Nov 30 10:50:25 volumio volumio[880]: info: Nov 30 10:50:25 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:25 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:25 volumio volumio[880]: info: Nov 30 10:50:25 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:25 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:25 volumio volumio[880]: info: Nov 30 10:50:25 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:25 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:25 volumio volumio[880]: info: Nov 30 10:50:25 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:25 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:25 volumio volumio[880]: info: Nov 30 10:50:25 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:25 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:25 volumio volumio[880]: info: Nov 30 10:50:25 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:25 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:25 volumio volumio[880]: info: Nov 30 10:50:25 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:25 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:25 volumio volumio[880]: info: Nov 30 10:50:25 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:25 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:25 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:50:25 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:50:25 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:50:25 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:50:25 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:25 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:25 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:50:25 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:25 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:25 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:25 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:25 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:25 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:25 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:25 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:25 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":244964,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:25 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:25 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:25 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:25 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:25 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:25 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":244964,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:25 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:25 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:25 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:25 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:25 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:25 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":244964,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:25 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:25 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:25 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:25 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:25 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:25 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":244964,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:25 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:25 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:25 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:25 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:25 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:25 volumio volumio[880]: info: ------------------------------ 88ms Nov 30 10:50:25 volumio volumio[880]: info: ------------------------------ 87ms Nov 30 10:50:25 volumio volumio[880]: info: ------------------------------ 86ms Nov 30 10:50:25 volumio volumio[880]: info: ------------------------------ 84ms Nov 30 10:50:26 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:50:27 volumio volumio[880]: info: Nov 30 10:50:27 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:27 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:27 volumio volumio[880]: info: Nov 30 10:50:27 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:27 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:27 volumio volumio[880]: info: Nov 30 10:50:27 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:27 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:27 volumio volumio[880]: info: Nov 30 10:50:27 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:27 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:27 volumio volumio[880]: info: Nov 30 10:50:27 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:27 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:27 volumio volumio[880]: info: Nov 30 10:50:27 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:27 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:27 volumio volumio[880]: info: Nov 30 10:50:27 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:27 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:27 volumio volumio[880]: info: Nov 30 10:50:27 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:27 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:27 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:50:27 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:50:27 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:27 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:50:27 volumio volumio[880]: info: ------------------------------ 5ms Nov 30 10:50:27 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:50:27 volumio volumio[880]: info: ------------------------------ 5ms Nov 30 10:50:27 volumio volumio[880]: info: sendMpdCommand status took 4 milliseconds Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:27 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:50:27 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:27 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:27 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:27 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:27 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:27 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:27 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":246835,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:27 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:27 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:27 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:27 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:27 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:27 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":246835,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:27 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:27 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:27 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:27 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:27 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:27 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":246835,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:27 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:27 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:27 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:27 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:27 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:27 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":246835,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:27 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:27 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:27 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:27 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:27 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:27 volumio volumio[880]: info: ------------------------------ 85ms Nov 30 10:50:27 volumio volumio[880]: info: ------------------------------ 83ms Nov 30 10:50:27 volumio volumio[880]: info: ------------------------------ 82ms Nov 30 10:50:27 volumio volumio[880]: info: ------------------------------ 81ms Nov 30 10:50:32 volumio volumio[880]: info: Nov 30 10:50:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:32 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:32 volumio volumio[880]: info: Nov 30 10:50:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:32 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:32 volumio volumio[880]: info: Nov 30 10:50:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:32 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:32 volumio volumio[880]: info: Nov 30 10:50:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:32 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:32 volumio volumio[880]: info: Nov 30 10:50:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:32 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:32 volumio volumio[880]: info: Nov 30 10:50:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:32 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:32 volumio volumio[880]: info: Nov 30 10:50:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:32 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:32 volumio volumio[880]: info: Nov 30 10:50:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:32 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:32 volumio volumio[880]: info: ------------------------------ 12ms Nov 30 10:50:32 volumio volumio[880]: info: sendMpdCommand status took 11 milliseconds Nov 30 10:50:32 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:50:32 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:50:32 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:50:32 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:50:32 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:32 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:32 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251335,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:32 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251335,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:32 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251335,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:32 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251335,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:32 volumio volumio[880]: info: ------------------------------ 82ms Nov 30 10:50:32 volumio volumio[880]: info: ------------------------------ 81ms Nov 30 10:50:32 volumio volumio[880]: info: ------------------------------ 81ms Nov 30 10:50:32 volumio volumio[880]: info: ------------------------------ 79ms Nov 30 10:50:35 volumio nmbd[819]: [2024/11/30 10:50:35.005209, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 30 10:50:35 volumio nmbd[819]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.226 for name WORKGROUP<1d>. Nov 30 10:50:35 volumio nmbd[819]: This response was from IP 192.168.1.133, reporting an IP address of 192.168.1.133. Nov 30 10:50:36 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:50:36 volumio volumio[880]: info: Listing playlists Nov 30 10:50:36 volumio volumio[880]: info: Listing playlists Nov 30 10:50:38 volumio volumio[880]: info: Nov 30 10:50:38 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:38 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:38 volumio volumio[880]: info: Nov 30 10:50:38 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:38 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:38 volumio volumio[880]: info: Nov 30 10:50:38 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:38 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:38 volumio volumio[880]: info: Nov 30 10:50:38 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:38 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:38 volumio volumio[880]: info: Nov 30 10:50:38 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:38 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:38 volumio volumio[880]: info: Nov 30 10:50:38 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:38 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:38 volumio volumio[880]: info: Nov 30 10:50:38 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:38 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:38 volumio volumio[880]: info: Nov 30 10:50:38 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:38 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:38 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:50:38 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:50:38 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:50:38 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:38 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:38 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:50:38 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:50:38 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:38 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:38 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:38 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:38 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:38 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:38 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:38 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:38 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":257585,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:38 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:38 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:38 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:38 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:38 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:38 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":257585,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:38 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:38 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:38 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:38 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:38 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:38 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":257585,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:38 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:38 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:38 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:38 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:38 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:38 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":257585,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:38 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:38 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:38 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:38 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:38 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:38 volumio volumio[880]: info: ------------------------------ 84ms Nov 30 10:50:38 volumio volumio[880]: info: ------------------------------ 83ms Nov 30 10:50:38 volumio volumio[880]: info: ------------------------------ 82ms Nov 30 10:50:38 volumio volumio[880]: info: ------------------------------ 82ms Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 5ms Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":263203,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":263203,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":263203,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":263203,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 84ms Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 82ms Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 81ms Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 80ms Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:44 volumio volumio[880]: info: Nov 30 10:50:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 5ms Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand status took 4 milliseconds Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":263954,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":263954,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":263954,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":263954,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 86ms Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 84ms Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 83ms Nov 30 10:50:44 volumio volumio[880]: info: ------------------------------ 83ms Nov 30 10:50:46 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:50:47 volumio volumio[880]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ampswitch/volumio/buster/armhf Nov 30 10:50:49 volumio volumio[880]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ampswitch/volumio/buster/armhf Nov 30 10:50:49 volumio volumio[880]: info: Folder /tmp/plugins removed Nov 30 10:50:49 volumio volumio[880]: info: Check plugin dependencies Nov 30 10:50:49 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 30 10:50:49 volumio volumio[880]: info: Checking if plugin already exists Nov 30 10:50:49 volumio volumio[880]: info: Rename folder Nov 30 10:50:49 volumio volumio[880]: info: Folder /tmp/downloaded_plugin.zip removed Nov 30 10:50:49 volumio volumio[880]: info: Move to category Nov 30 10:50:50 volumio volumio[880]: info: Checking if install.sh is present Nov 30 10:50:50 volumio volumio[880]: info: Executing install.sh Nov 30 10:50:50 volumio volumio[880]: info: Nov 30 10:50:50 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:50 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:50 volumio volumio[880]: info: Nov 30 10:50:50 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:50 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:50 volumio volumio[880]: info: Nov 30 10:50:50 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:50 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:50 volumio volumio[880]: info: Nov 30 10:50:50 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:50 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:50 volumio volumio[880]: info: Nov 30 10:50:50 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:50 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:50 volumio volumio[880]: info: Nov 30 10:50:50 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:50 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:50 volumio volumio[880]: info: Nov 30 10:50:50 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:50 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:50 volumio volumio[880]: info: Nov 30 10:50:50 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:50 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:50 volumio volumio[880]: info: ------------------------------ 16ms Nov 30 10:50:50 volumio volumio[880]: info: sendMpdCommand status took 15 milliseconds Nov 30 10:50:50 volumio volumio[880]: info: ------------------------------ 14ms Nov 30 10:50:50 volumio volumio[880]: info: sendMpdCommand status took 13 milliseconds Nov 30 10:50:50 volumio volumio[880]: info: ------------------------------ 12ms Nov 30 10:50:50 volumio volumio[880]: info: sendMpdCommand status took 12 milliseconds Nov 30 10:50:50 volumio volumio[880]: info: ------------------------------ 11ms Nov 30 10:50:50 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:50 volumio volumio[880]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 30 10:50:50 volumio volumio[880]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 30 10:50:50 volumio volumio[880]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 30 10:50:50 volumio volumio[880]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:50 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:50 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:50 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":269709,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:50 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:50 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:50 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:50 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":269709,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:50 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:50 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:50 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:50 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":269709,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:50 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:50 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:50 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:50 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":269709,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:50 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:50 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:50 volumio volumio[880]: info: ------------------------------ 109ms Nov 30 10:50:50 volumio sudo[2702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/ampswitch/install.sh Nov 30 10:50:50 volumio volumio[880]: info: ------------------------------ 108ms Nov 30 10:50:50 volumio volumio[880]: info: ------------------------------ 106ms Nov 30 10:50:50 volumio sudo[2702]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 10:50:50 volumio sudo[2702]: pam_unix(sudo:session): session closed for user root Nov 30 10:50:50 volumio volumio[880]: info: Install script completed Nov 30 10:50:50 volumio volumio[880]: info: Adding reference to registry Nov 30 10:50:50 volumio volumio[880]: info: Done installing plugin. Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 30 10:50:50 volumio volumio[880]: info: Folder /tmp/plugins removed Nov 30 10:50:50 volumio volumio[880]: info: Folder /tmp/downloaded_plugin.zip removed Nov 30 10:50:50 volumio volumio[880]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 10:50:50 volumio volumio[880]: info: Folder /data/temp removed Nov 30 10:50:51 volumio volumio[880]: info: Nov 30 10:50:51 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:51 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:51 volumio volumio[880]: info: Nov 30 10:50:51 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:51 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:51 volumio volumio[880]: info: Nov 30 10:50:51 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:51 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:51 volumio volumio[880]: info: Nov 30 10:50:51 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:51 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:51 volumio volumio[880]: info: Nov 30 10:50:51 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:51 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:51 volumio volumio[880]: info: Nov 30 10:50:51 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:51 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:51 volumio volumio[880]: info: Nov 30 10:50:51 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:51 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:51 volumio volumio[880]: info: Nov 30 10:50:51 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:51 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:51 volumio volumio[880]: info: ------------------------------ 13ms Nov 30 10:50:51 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:50:51 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:50:51 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:51 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:51 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:51 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:51 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:51 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:51 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:51 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:51 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:51 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:51 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":270703,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:51 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:51 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:51 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:51 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:51 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:51 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":270703,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:51 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:51 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:51 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:51 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:51 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:51 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":270703,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:51 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:51 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:51 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:51 volumio volumio[880]: info: ------------------------------ 71ms Nov 30 10:50:51 volumio volumio[880]: info: ------------------------------ 67ms Nov 30 10:50:51 volumio volumio[880]: info: ------------------------------ 65ms Nov 30 10:50:51 volumio volumio[880]: info: sendMpdCommand playlistinfo took 57 milliseconds Nov 30 10:50:51 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:51 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:51 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:51 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":270703,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:51 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:51 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:51 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:51 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:51 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:51 volumio volumio[880]: info: ------------------------------ 84ms Nov 30 10:50:52 volumio volumio[880]: info: Enabling plugin ampswitch Nov 30 10:50:52 volumio volumio[880]: info: Loading plugin "ampswitch"... Nov 30 10:50:53 volumio volumio[880]: info: PLUGIN START: ampswitch Nov 30 10:50:53 volumio volumio[880]: info: Done. Nov 30 10:50:53 volumio volumio[880]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 30 10:50:53 volumio volumio[880]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 10:50:53 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:50:53 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: na Nov 30 10:50:53 volumio volumio[880]: info: [ASDebug] Togle GPIO: ON Nov 30 10:50:53 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:50:56 volumio volumio[880]: info: Nov 30 10:50:56 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:56 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:56 volumio volumio[880]: info: Nov 30 10:50:56 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:56 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:56 volumio volumio[880]: info: Nov 30 10:50:56 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:56 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:56 volumio volumio[880]: info: Nov 30 10:50:56 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:56 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:56 volumio volumio[880]: info: Nov 30 10:50:56 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:56 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:56 volumio volumio[880]: info: Nov 30 10:50:56 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:56 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:56 volumio volumio[880]: info: Nov 30 10:50:56 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:50:56 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:50:56 volumio volumio[880]: info: Nov 30 10:50:56 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:50:56 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:50:56 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:50:56 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:50:56 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:50:56 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:50:56 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:50:56 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:50:56 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:56 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:50:56 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:56 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:50:56 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:56 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:56 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:56 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":275203,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:56 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:56 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:56 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:56 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:56 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:56 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":275203,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:56 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:56 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:56 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:56 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:56 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:56 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":275203,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:56 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:56 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:56 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:56 volumio volumio[880]: info: ------------------------------ 84ms Nov 30 10:50:56 volumio volumio[880]: info: ------------------------------ 83ms Nov 30 10:50:56 volumio volumio[880]: info: ------------------------------ 80ms Nov 30 10:50:56 volumio volumio[880]: info: sendMpdCommand playlistinfo took 70 milliseconds Nov 30 10:50:56 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:50:56 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:50:56 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:56 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":275203,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:50:56 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:50:56 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:56 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:56 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:50:56 volumio volumio[880]: info: ------------------------------ 104ms Nov 30 10:50:56 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:50:56 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:50:56 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:50:56 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:50:56 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:50:56 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:50:56 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:50:56 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:50:56 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:50:56 volumio volumio[880]: info: Listing playlists Nov 30 10:50:56 volumio volumio[880]: info: Listing playlists Nov 30 10:50:59 volumio volumio[880]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 10:50:59 volumio volumio[880]: info: [ASDebug] Setting UI defaults Nov 30 10:50:59 volumio volumio[880]: info: [ASDebug] Port: 24 Nov 30 10:50:59 volumio volumio[880]: info: [ASDebug] Inverted: false Nov 30 10:50:59 volumio volumio[880]: info: [ASDebug] Latched: false Nov 30 10:50:59 volumio volumio[880]: info: [ASDebug] On pulse width: 500 Nov 30 10:50:59 volumio volumio[880]: info: [ASDebug] Off pulse width: 500 Nov 30 10:51:02 volumio volumio[880]: info: Nov 30 10:51:02 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:02 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:02 volumio volumio[880]: info: Nov 30 10:51:02 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:02 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:02 volumio volumio[880]: info: Nov 30 10:51:02 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:02 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:02 volumio volumio[880]: info: Nov 30 10:51:02 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:02 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:02 volumio volumio[880]: info: Nov 30 10:51:02 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:02 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:02 volumio volumio[880]: info: Nov 30 10:51:02 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:02 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:02 volumio volumio[880]: info: Nov 30 10:51:02 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:02 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:02 volumio volumio[880]: info: Nov 30 10:51:02 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:02 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:02 volumio volumio[880]: info: ------------------------------ 18ms Nov 30 10:51:02 volumio volumio[880]: info: sendMpdCommand status took 16 milliseconds Nov 30 10:51:02 volumio volumio[880]: info: ------------------------------ 15ms Nov 30 10:51:02 volumio volumio[880]: info: sendMpdCommand status took 14 milliseconds Nov 30 10:51:02 volumio volumio[880]: info: ------------------------------ 14ms Nov 30 10:51:02 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:02 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:51:02 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:02 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:02 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:51:02 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:02 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:02 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:02 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:02 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":281704,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:02 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:02 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:02 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:02 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":281704,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:02 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:02 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:02 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:02 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":281704,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:02 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:02 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:02 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:02 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":281704,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:02 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:02 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:02 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:02 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:02 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:02 volumio volumio[880]: info: ------------------------------ 104ms Nov 30 10:51:02 volumio volumio[880]: info: ------------------------------ 102ms Nov 30 10:51:02 volumio volumio[880]: info: ------------------------------ 101ms Nov 30 10:51:02 volumio volumio[880]: info: ------------------------------ 94ms Nov 30 10:51:02 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:02 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:02 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:02 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:02 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:02 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:02 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:02 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:06 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:51:07 volumio volumio[880]: info: Nov 30 10:51:07 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:07 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:07 volumio volumio[880]: info: Nov 30 10:51:07 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:07 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:07 volumio volumio[880]: info: Nov 30 10:51:07 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:07 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:07 volumio volumio[880]: info: Nov 30 10:51:07 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:07 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:07 volumio volumio[880]: info: Nov 30 10:51:07 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:07 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:07 volumio volumio[880]: info: Nov 30 10:51:07 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:07 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:07 volumio volumio[880]: info: Nov 30 10:51:07 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:07 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:07 volumio volumio[880]: info: Nov 30 10:51:07 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:07 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:07 volumio volumio[880]: info: ------------------------------ 11ms Nov 30 10:51:07 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:51:07 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:51:07 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:07 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:51:07 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:51:07 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:51:07 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:07 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:07 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:51:07 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:07 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:07 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:07 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:07 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:07 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":286955,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:07 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:07 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:07 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:07 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:07 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:07 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":286955,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:07 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:07 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:07 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:07 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:07 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:07 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":286955,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:07 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:07 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:07 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:07 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:07 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:07 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":286955,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:07 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:07 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:07 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:07 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:07 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:08 volumio volumio[880]: info: ------------------------------ 58ms Nov 30 10:51:08 volumio volumio[880]: info: ------------------------------ 57ms Nov 30 10:51:08 volumio volumio[880]: info: ------------------------------ 56ms Nov 30 10:51:08 volumio volumio[880]: info: ------------------------------ 55ms Nov 30 10:51:08 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:08 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:08 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:08 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:08 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:08 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:08 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:08 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:08 volumio volumio[880]: info: Nov 30 10:51:08 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:08 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:08 volumio volumio[880]: info: Nov 30 10:51:08 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:08 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:08 volumio volumio[880]: info: Nov 30 10:51:08 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:08 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:08 volumio volumio[880]: info: Nov 30 10:51:08 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:08 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:08 volumio volumio[880]: info: Nov 30 10:51:08 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:08 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:08 volumio volumio[880]: info: Nov 30 10:51:08 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:08 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:08 volumio volumio[880]: info: Nov 30 10:51:08 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:08 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:08 volumio volumio[880]: info: Nov 30 10:51:08 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:08 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:08 volumio volumio[880]: info: ------------------------------ 12ms Nov 30 10:51:08 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:51:08 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:51:08 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:08 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:51:08 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:08 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:51:08 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:08 volumio volumio[880]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 30 10:51:08 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:08 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:51:08 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:08 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:08 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:08 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":287959,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:08 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:08 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:08 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:08 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":287959,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:08 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:08 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:08 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:08 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":287959,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:08 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:08 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:08 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:08 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":287959,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:08 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:08 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:08 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:08 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:08 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:09 volumio volumio[880]: info: ------------------------------ 50ms Nov 30 10:51:09 volumio volumio[880]: info: ------------------------------ 48ms Nov 30 10:51:09 volumio volumio[880]: info: ------------------------------ 46ms Nov 30 10:51:09 volumio volumio[880]: info: ------------------------------ 45ms Nov 30 10:51:09 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:09 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:09 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:09 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:09 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:09 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:09 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:09 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:14 volumio volumio[880]: info: Nov 30 10:51:14 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:14 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:14 volumio volumio[880]: info: Nov 30 10:51:14 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:14 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:14 volumio volumio[880]: info: Nov 30 10:51:14 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:14 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:14 volumio volumio[880]: info: Nov 30 10:51:14 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:14 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:14 volumio volumio[880]: info: Nov 30 10:51:14 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:14 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:14 volumio volumio[880]: info: Nov 30 10:51:14 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:14 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:14 volumio volumio[880]: info: Nov 30 10:51:14 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:14 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:14 volumio volumio[880]: info: Nov 30 10:51:14 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:14 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:14 volumio volumio[880]: info: ------------------------------ 13ms Nov 30 10:51:14 volumio volumio[880]: info: sendMpdCommand status took 12 milliseconds Nov 30 10:51:14 volumio volumio[880]: info: ------------------------------ 12ms Nov 30 10:51:14 volumio volumio[880]: info: sendMpdCommand status took 12 milliseconds Nov 30 10:51:14 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:51:14 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:14 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:51:14 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:14 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:51:14 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:14 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:51:14 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:14 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:14 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:14 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":293075,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:14 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:14 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:14 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:14 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":293075,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:14 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:14 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:14 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:14 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":293075,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:14 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:14 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:14 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:14 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":293075,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:14 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:14 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:14 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:14 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:14 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:14 volumio volumio[880]: info: ------------------------------ 56ms Nov 30 10:51:14 volumio volumio[880]: info: ------------------------------ 55ms Nov 30 10:51:14 volumio volumio[880]: info: ------------------------------ 54ms Nov 30 10:51:14 volumio volumio[880]: info: ------------------------------ 52ms Nov 30 10:51:14 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:14 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:14 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:14 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:14 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:14 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:14 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:14 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:15 volumio volumio[880]: info: Nov 30 10:51:15 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:15 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:15 volumio volumio[880]: info: Nov 30 10:51:15 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:15 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:15 volumio volumio[880]: info: Nov 30 10:51:15 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:15 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:15 volumio volumio[880]: info: Nov 30 10:51:15 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:15 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:15 volumio volumio[880]: info: Nov 30 10:51:15 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:15 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:15 volumio volumio[880]: info: Nov 30 10:51:15 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:15 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:15 volumio volumio[880]: info: Nov 30 10:51:15 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:15 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:15 volumio volumio[880]: info: Nov 30 10:51:15 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:15 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:15 volumio volumio[880]: info: ------------------------------ 17ms Nov 30 10:51:15 volumio volumio[880]: info: sendMpdCommand status took 15 milliseconds Nov 30 10:51:15 volumio volumio[880]: info: ------------------------------ 14ms Nov 30 10:51:15 volumio volumio[880]: info: sendMpdCommand status took 14 milliseconds Nov 30 10:51:15 volumio volumio[880]: info: ------------------------------ 13ms Nov 30 10:51:15 volumio volumio[880]: info: sendMpdCommand status took 11 milliseconds Nov 30 10:51:15 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:51:15 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:15 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:15 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:51:15 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:15 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:15 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:15 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:15 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":294205,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:15 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:15 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:15 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:15 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":294205,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:15 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:15 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:15 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:15 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":294205,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:15 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:15 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:15 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:15 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":294205,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:15 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:15 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:15 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:15 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:15 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:15 volumio volumio[880]: info: ------------------------------ 60ms Nov 30 10:51:15 volumio volumio[880]: info: ------------------------------ 58ms Nov 30 10:51:15 volumio volumio[880]: info: ------------------------------ 57ms Nov 30 10:51:15 volumio volumio[880]: info: ------------------------------ 51ms Nov 30 10:51:15 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:15 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:15 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:15 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:15 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:15 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:15 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:15 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:16 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:51:16 volumio volumio[880]: info: Listing playlists Nov 30 10:51:16 volumio volumio[880]: info: Listing playlists Nov 30 10:51:20 volumio volumio[880]: info: Nov 30 10:51:20 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:20 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:20 volumio volumio[880]: info: Nov 30 10:51:20 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:20 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:20 volumio volumio[880]: info: Nov 30 10:51:20 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:20 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:20 volumio volumio[880]: info: Nov 30 10:51:20 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:20 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:20 volumio volumio[880]: info: Nov 30 10:51:20 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:20 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:20 volumio volumio[880]: info: Nov 30 10:51:20 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:20 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:20 volumio volumio[880]: info: Nov 30 10:51:20 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:20 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:20 volumio volumio[880]: info: Nov 30 10:51:20 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:20 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:20 volumio volumio[880]: info: ------------------------------ 11ms Nov 30 10:51:20 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:51:20 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:51:20 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:20 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:51:20 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:20 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:20 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:51:20 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:20 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:20 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:20 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:20 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":299206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:20 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:20 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:20 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:20 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:20 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:20 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":299206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:20 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:20 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:20 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:20 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:20 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:20 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":299206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:20 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:20 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:20 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:20 volumio volumio[880]: info: ------------------------------ 46ms Nov 30 10:51:20 volumio volumio[880]: info: ------------------------------ 44ms Nov 30 10:51:20 volumio volumio[880]: info: ------------------------------ 43ms Nov 30 10:51:20 volumio volumio[880]: info: sendMpdCommand playlistinfo took 33 milliseconds Nov 30 10:51:20 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:20 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:20 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:20 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":299206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:20 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:20 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:20 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:20 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:20 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:20 volumio volumio[880]: info: ------------------------------ 53ms Nov 30 10:51:20 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:20 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:20 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:20 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:20 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:20 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:20 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:20 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:51:26 volumio volumio[880]: info: Nov 30 10:51:26 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:26 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:26 volumio volumio[880]: info: Nov 30 10:51:26 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:26 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:26 volumio volumio[880]: info: Nov 30 10:51:26 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:26 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:26 volumio volumio[880]: info: Nov 30 10:51:26 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:26 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:26 volumio volumio[880]: info: Nov 30 10:51:26 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:26 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:26 volumio volumio[880]: info: Nov 30 10:51:26 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:26 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:26 volumio volumio[880]: info: Nov 30 10:51:26 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:26 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:26 volumio volumio[880]: info: Nov 30 10:51:26 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:26 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:26 volumio volumio[880]: info: ------------------------------ 12ms Nov 30 10:51:26 volumio volumio[880]: info: sendMpdCommand status took 11 milliseconds Nov 30 10:51:26 volumio volumio[880]: info: ------------------------------ 11ms Nov 30 10:51:26 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:26 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:51:26 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:26 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:51:26 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:26 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:51:26 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:26 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:26 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:26 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:26 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:26 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:26 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":305582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:26 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:26 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:26 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:26 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:26 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:26 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":305582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:26 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:26 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:26 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:26 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:26 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:26 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":305582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:26 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:26 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:26 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:26 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:26 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:26 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":305582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:26 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:26 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:26 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:26 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:26 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:26 volumio volumio[880]: info: ------------------------------ 49ms Nov 30 10:51:26 volumio volumio[880]: info: ------------------------------ 47ms Nov 30 10:51:26 volumio volumio[880]: info: ------------------------------ 46ms Nov 30 10:51:26 volumio volumio[880]: info: ------------------------------ 43ms Nov 30 10:51:26 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:26 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:26 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:26 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:26 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:26 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:26 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:26 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:31 volumio volumio[880]: info: Nov 30 10:51:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:31 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:31 volumio volumio[880]: info: Nov 30 10:51:31 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:31 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:31 volumio volumio[880]: info: Nov 30 10:51:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:31 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:31 volumio volumio[880]: info: Nov 30 10:51:31 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:31 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:31 volumio volumio[880]: info: Nov 30 10:51:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:31 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:31 volumio volumio[880]: info: Nov 30 10:51:31 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:31 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:31 volumio volumio[880]: info: Nov 30 10:51:31 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:31 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:31 volumio volumio[880]: info: Nov 30 10:51:31 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:31 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:31 volumio volumio[880]: info: ------------------------------ 13ms Nov 30 10:51:31 volumio volumio[880]: info: sendMpdCommand status took 11 milliseconds Nov 30 10:51:31 volumio volumio[880]: info: ------------------------------ 12ms Nov 30 10:51:31 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:51:31 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:51:31 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:31 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:51:31 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:31 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:31 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:31 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":310582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:31 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:31 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:31 volumio volumio[880]: info: ------------------------------ 27ms Nov 30 10:51:31 volumio volumio[880]: info: sendMpdCommand playlistinfo took 18 milliseconds Nov 30 10:51:31 volumio volumio[880]: info: sendMpdCommand playlistinfo took 18 milliseconds Nov 30 10:51:31 volumio volumio[880]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:31 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:31 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:31 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":310582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:31 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:31 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:31 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:31 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":310582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:31 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:31 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:31 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:31 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":310582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:31 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:31 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:31 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:31 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:31 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:31 volumio volumio[880]: info: ------------------------------ 60ms Nov 30 10:51:31 volumio volumio[880]: info: ------------------------------ 58ms Nov 30 10:51:31 volumio volumio[880]: info: ------------------------------ 56ms Nov 30 10:51:31 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:31 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:31 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:31 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:31 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:31 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:31 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:31 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:32 volumio volumio[880]: info: Nov 30 10:51:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:32 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:32 volumio volumio[880]: info: Nov 30 10:51:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:32 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:32 volumio volumio[880]: info: Nov 30 10:51:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:32 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:32 volumio volumio[880]: info: Nov 30 10:51:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:32 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:32 volumio volumio[880]: info: Nov 30 10:51:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:32 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:32 volumio volumio[880]: info: Nov 30 10:51:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:32 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:32 volumio volumio[880]: info: Nov 30 10:51:32 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:32 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:32 volumio volumio[880]: info: Nov 30 10:51:32 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:32 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:32 volumio volumio[880]: info: ------------------------------ 11ms Nov 30 10:51:32 volumio volumio[880]: info: sendMpdCommand status took 10 milliseconds Nov 30 10:51:32 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:51:32 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:32 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:51:32 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:32 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:51:32 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:51:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:32 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:32 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:32 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":311951,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:32 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":311951,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:32 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":311951,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:32 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:32 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":311951,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:32 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:32 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:32 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:32 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:32 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:32 volumio volumio[880]: info: ------------------------------ 56ms Nov 30 10:51:32 volumio volumio[880]: info: ------------------------------ 55ms Nov 30 10:51:32 volumio volumio[880]: info: ------------------------------ 54ms Nov 30 10:51:32 volumio volumio[880]: info: ------------------------------ 53ms Nov 30 10:51:32 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:32 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:32 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:32 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:32 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:32 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:32 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:32 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:36 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:51:36 volumio volumio[880]: info: Listing playlists Nov 30 10:51:36 volumio volumio[880]: info: Listing playlists Nov 30 10:51:37 volumio volumio[880]: info: Nov 30 10:51:37 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:37 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:37 volumio volumio[880]: info: Nov 30 10:51:37 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:37 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:37 volumio volumio[880]: info: Nov 30 10:51:37 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:37 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:37 volumio volumio[880]: info: Nov 30 10:51:37 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:37 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:37 volumio volumio[880]: info: Nov 30 10:51:37 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:37 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:37 volumio volumio[880]: info: Nov 30 10:51:37 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:37 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:37 volumio volumio[880]: info: Nov 30 10:51:37 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:37 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:37 volumio volumio[880]: info: Nov 30 10:51:37 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:37 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:37 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:51:37 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:37 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:51:37 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:51:37 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:51:37 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:37 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:51:37 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:37 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 30 10:51:37 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:37 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:37 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:37 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":316827,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:37 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:37 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:37 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:37 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":316827,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:37 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:37 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:37 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:37 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":316827,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:37 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:37 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:37 volumio volumio[880]: info: ------------------------------ 44ms Nov 30 10:51:37 volumio volumio[880]: info: ------------------------------ 43ms Nov 30 10:51:37 volumio volumio[880]: info: ------------------------------ 42ms Nov 30 10:51:37 volumio volumio[880]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 30 10:51:37 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:37 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:37 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":316827,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:37 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:37 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:37 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:37 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:37 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:37 volumio volumio[880]: info: ------------------------------ 52ms Nov 30 10:51:37 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:37 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:37 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:37 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:37 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:37 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:37 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:37 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:39 volumio volumio[880]: info: Nov 30 10:51:39 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:39 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:39 volumio volumio[880]: info: Nov 30 10:51:39 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:39 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:39 volumio volumio[880]: info: Nov 30 10:51:39 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:39 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:39 volumio volumio[880]: info: Nov 30 10:51:39 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:39 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:39 volumio volumio[880]: info: Nov 30 10:51:39 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:39 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:39 volumio volumio[880]: info: Nov 30 10:51:39 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:39 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:39 volumio volumio[880]: info: Nov 30 10:51:39 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:39 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:39 volumio volumio[880]: info: Nov 30 10:51:39 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:39 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:39 volumio volumio[880]: info: ------------------------------ 10ms Nov 30 10:51:39 volumio volumio[880]: info: sendMpdCommand status took 8 milliseconds Nov 30 10:51:39 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:51:39 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:39 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:51:39 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:51:39 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:51:39 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:39 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:51:39 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:39 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:39 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:39 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:39 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:39 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":318711,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:39 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:39 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:39 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:39 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":318711,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:39 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:39 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:39 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:39 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":318711,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:39 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:39 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:39 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:39 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":318711,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:39 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:39 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:39 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:39 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:39 volumio volumio[880]: info: ------------------------------ 43ms Nov 30 10:51:39 volumio volumio[880]: info: ------------------------------ 42ms Nov 30 10:51:39 volumio volumio[880]: info: ------------------------------ 40ms Nov 30 10:51:39 volumio volumio[880]: info: ------------------------------ 39ms Nov 30 10:51:39 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:39 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:39 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:39 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:39 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:39 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:39 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:39 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:42 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 30 10:51:42 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 30 10:51:44 volumio volumio[880]: info: Nov 30 10:51:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:44 volumio volumio[880]: info: Nov 30 10:51:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:44 volumio volumio[880]: info: Nov 30 10:51:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:44 volumio volumio[880]: info: Nov 30 10:51:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:44 volumio volumio[880]: info: ------------------------------ 5ms Nov 30 10:51:44 volumio volumio[880]: info: sendMpdCommand status took 4 milliseconds Nov 30 10:51:44 volumio volumio[880]: info: ------------------------------ 3ms Nov 30 10:51:44 volumio volumio[880]: info: sendMpdCommand status took 2 milliseconds Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:44 volumio volumio[880]: info: Nov 30 10:51:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:44 volumio volumio[880]: info: Nov 30 10:51:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:44 volumio volumio[880]: info: Nov 30 10:51:44 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:44 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:44 volumio volumio[880]: info: Nov 30 10:51:44 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:44 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 30 10:51:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 30 10:51:44 volumio volumio[880]: info: ------------------------------ 6ms Nov 30 10:51:44 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:51:44 volumio volumio[880]: info: ------------------------------ 5ms Nov 30 10:51:44 volumio volumio[880]: info: sendMpdCommand status took 4 milliseconds Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":323199,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":323199,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:44 volumio volumio[880]: info: ------------------------------ 35ms Nov 30 10:51:44 volumio volumio[880]: info: ------------------------------ 33ms Nov 30 10:51:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 30 10:51:44 volumio volumio[880]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:44 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":323199,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:44 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:44 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":323199,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:44 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:44 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:44 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:44 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:44 volumio volumio[880]: info: ------------------------------ 49ms Nov 30 10:51:44 volumio volumio[880]: info: ------------------------------ 48ms Nov 30 10:51:44 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:44 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:44 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:44 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:46 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Nov 30 10:51:46 volumio volumio[880]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 30 10:51:46 volumio volumio[880]: info: Uninstalling plugin ampswitch Nov 30 10:51:46 volumio volumio[880]: info: [ASDebug] Port: 24 Nov 30 10:51:46 volumio volumio[880]: info: [ASDebug] Inverted: false Nov 30 10:51:46 volumio volumio[880]: info: [ASDebug] Delay: 720 Nov 30 10:51:50 volumio volumio[880]: info: Nov 30 10:51:50 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:50 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:50 volumio volumio[880]: info: Nov 30 10:51:50 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:50 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:50 volumio volumio[880]: info: Nov 30 10:51:50 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:50 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:50 volumio volumio[880]: info: Nov 30 10:51:50 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:50 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:50 volumio volumio[880]: info: Nov 30 10:51:50 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:50 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:50 volumio volumio[880]: info: Nov 30 10:51:50 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:50 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:50 volumio volumio[880]: info: Nov 30 10:51:50 volumio volumio[880]: ---------------------------- MPD announces system playlist update Nov 30 10:51:50 volumio volumio[880]: info: Ignoring MPD Status Update Nov 30 10:51:50 volumio volumio[880]: info: Nov 30 10:51:50 volumio volumio[880]: ---------------------------- MPD announces state update: player Nov 30 10:51:50 volumio volumio[880]: info: ControllerMpd::getState Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Nov 30 10:51:50 volumio volumio[880]: info: ------------------------------ 9ms Nov 30 10:51:50 volumio volumio[880]: info: sendMpdCommand status took 9 milliseconds Nov 30 10:51:50 volumio volumio[880]: info: ------------------------------ 8ms Nov 30 10:51:50 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Nov 30 10:51:50 volumio volumio[880]: info: ------------------------------ 7ms Nov 30 10:51:50 volumio volumio[880]: info: sendMpdCommand status took 6 milliseconds Nov 30 10:51:50 volumio volumio[880]: info: ------------------------------ 5ms Nov 30 10:51:50 volumio volumio[880]: info: sendMpdCommand status took 4 milliseconds Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::parseState Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 10:51:50 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:50 volumio volumio[880]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 10:51:50 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:51:50 volumio volumio[880]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:50 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Nov 30 10:51:50 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:50 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":329952,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:50 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:50 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:50 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:50 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":329952,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:50 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:50 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:50 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:50 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":329952,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:50 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:50 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:50 volumio volumio[880]: info: ControllerMpd::pushState Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::servicePushState Nov 30 10:51:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:50 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":329952,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"2.mp3","artist":"Radio2","album":null,"uri":"http://icestreaming.rai.it/2.mp3","trackType":"mp3"} Nov 30 10:51:50 volumio volumio[880]: verbose: CURRENT POSITION 0 Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Nov 30 10:51:50 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:50 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:50 volumio volumio[880]: info: CoreStateMachine::pushState Nov 30 10:51:50 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Nov 30 10:51:50 volumio volumio[880]: info: ------------------------------ 51ms Nov 30 10:51:50 volumio volumio[880]: info: ------------------------------ 50ms Nov 30 10:51:50 volumio volumio[880]: info: ------------------------------ 49ms Nov 30 10:51:50 volumio volumio[880]: info: ------------------------------ 47ms Nov 30 10:51:50 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:50 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:50 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:50 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:50 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:50 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:50 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:50 volumio volumio[880]: info: [ASDebug] CurState: play PrevState: play Nov 30 10:51:52 volumio volumio[880]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 30 10:51:52 volumio volumio[880]: info: Uninstalling plugin ampswitch Nov 30 10:51:52 volumio volumio[880]: info: [ASDebug] Port: 24 Nov 30 10:51:52 volumio volumio[880]: info: [ASDebug] Inverted: false Nov 30 10:51:52 volumio volumio[880]: info: [ASDebug] Delay: 720 Nov 30 10:51:52 volumio volumio[880]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 10:51:52 volumio volumio[880]: Error: EBADF: bad file descriptor, close Nov 30 10:51:52 volumio volumio[880]: at Object.closeSync (fs.js:439:3) Nov 30 10:51:52 volumio volumio[880]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 30 10:51:52 volumio volumio[880]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 30 10:51:52 volumio volumio[880]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 30 10:51:52 volumio volumio[880]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 30 10:51:52 volumio volumio[880]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 30 10:51:52 volumio volumio[880]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 30 10:51:52 volumio volumio[880]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Nov 30 10:51:52 volumio volumio[880]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1479:22) Nov 30 10:51:52 volumio volumio[880]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1254:45) Nov 30 10:51:52 volumio volumio[880]: at Socket.emit (events.js:315:20) Nov 30 10:51:52 volumio volumio[880]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 30 10:51:52 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 30 10:51:52 volumio volumio[880]: errno: -9, Nov 30 10:51:52 volumio volumio[880]: syscall: 'close', Nov 30 10:51:52 volumio volumio[880]: code: 'EBADF' Nov 30 10:51:52 volumio volumio[880]: } Nov 30 10:51:52 volumio volumio[880]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 10:51:53 volumio sudo[3026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-30 10:50 Nov 30 10:51:53 volumio sudo[3026]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"