-- Logs begin at Thu 2019-02-14 11:12:08 CET, end at Thu 2024-07-04 23:17:04 CEST. -- Jul 04 23:16:03 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:13 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:13 volumio ntpd[579]: 194.59.207.235 local addr 192.168.1.76 -> Jul 04 23:16:13 volumio volumio[684]: info: Listing playlists Jul 04 23:16:13 volumio volumio[684]: info: Listing playlists Jul 04 23:16:23 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:26 volumio volumio[684]: info: Jul 04 23:16:26 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:26 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:26 volumio volumio[684]: info: Jul 04 23:16:26 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:26 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:26 volumio volumio[684]: info: Jul 04 23:16:26 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:26 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:26 volumio volumio[684]: info: sendMpdCommand status took 58 milliseconds Jul 04 23:16:26 volumio volumio[684]: info: sendMpdCommand status took 44 milliseconds Jul 04 23:16:26 volumio volumio[684]: info: sendMpdCommand status took 32 milliseconds Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:26 volumio volumio[684]: info: sendMpdCommand playlistinfo took 37 milliseconds Jul 04 23:16:26 volumio volumio[684]: info: sendMpdCommand playlistinfo took 39 milliseconds Jul 04 23:16:26 volumio volumio[684]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:26 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:26 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:26 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:26 volumio volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tears In Heaven","artist":"Eric Clapton","album":"Unplugged","uri":"http://192.168.1.21:50002/m/MP3/27953.mp3","trackType":"mp3"} Jul 04 23:16:26 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::syncState stateService stop Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::stPlaybackTimer Jul 04 23:16:26 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:26 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:26 volumio volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tears In Heaven","artist":"Eric Clapton","album":"Unplugged","uri":"http://192.168.1.21:50002/m/MP3/27953.mp3","trackType":"mp3"} Jul 04 23:16:26 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::syncState stateService stop Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:26 volumio volumio[684]: info: No code Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:26 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:26 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:26 volumio volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tears In Heaven","artist":"Eric Clapton","album":"Unplugged","uri":"http://192.168.1.21:50002/m/MP3/27953.mp3","trackType":"mp3"} Jul 04 23:16:26 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::syncState stateService stop Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:26 volumio volumio[684]: info: No code Jul 04 23:16:26 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:26 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:26 volumio volumio[684]: info: ------------------------------ 735ms Jul 04 23:16:26 volumio volumio[684]: info: ------------------------------ 725ms Jul 04 23:16:27 volumio volumio[684]: info: ------------------------------ 718ms Jul 04 23:16:27 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:27 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:27 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:27 volumio volumio[684]: info: Clearing queue after UPNP request Jul 04 23:16:27 volumio volumio[684]: info: CoreStateMachine::ClearQueue Jul 04 23:16:27 volumio volumio[684]: info: CoreStateMachine::stop Jul 04 23:16:27 volumio volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 23:16:27 volumio volumio[684]: info: CorePlayQueue::clearPlayQueue Jul 04 23:16:27 volumio volumio[684]: info: CorePlayQueue::saveQueue Jul 04 23:16:27 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:27 volumio volumio[684]: info: CoreCommandRouter::volumioPushQueue Jul 04 23:16:27 volumio volumio[684]: info: Jul 04 23:16:27 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:27 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:27 volumio volumio[684]: info: Jul 04 23:16:27 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:27 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:27 volumio volumio[684]: info: Jul 04 23:16:27 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:27 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:27 volumio volumio[684]: error: updateQueue error: null Jul 04 23:16:27 volumio volumio[684]: error: updateQueue error: null Jul 04 23:16:27 volumio volumio[684]: error: updateQueue error: null Jul 04 23:16:27 volumio volumio[684]: info: ------------------------------ 42ms Jul 04 23:16:27 volumio volumio[684]: info: ------------------------------ 34ms Jul 04 23:16:27 volumio volumio[684]: info: ------------------------------ 27ms Jul 04 23:16:27 volumio volumio[684]: info: Starting UPNP Playback Jul 04 23:16:27 volumio volumio[684]: info: Preparing playback through UPNP Jul 04 23:16:27 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:27 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:27 volumio volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 39ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 27ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 20ms Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 36ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 27ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 20ms Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 37ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 27ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 20ms Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 52ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 42ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 20ms Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 37ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 28ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 20ms Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: Jul 04 23:16:28 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:28 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 55ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 41ms Jul 04 23:16:28 volumio volumio[684]: info: ------------------------------ 23ms Jul 04 23:16:29 volumio volumio[684]: info: Jul 04 23:16:29 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:29 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:29 volumio volumio[684]: info: Jul 04 23:16:29 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:29 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:29 volumio volumio[684]: info: Jul 04 23:16:29 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:29 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:29 volumio volumio[684]: info: sendMpdCommand status took 67 milliseconds Jul 04 23:16:29 volumio volumio[684]: info: sendMpdCommand status took 58 milliseconds Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:29 volumio volumio[684]: info: sendMpdCommand status took 64 milliseconds Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:29 volumio volumio[684]: info: sendMpdCommand playlistinfo took 48 milliseconds Jul 04 23:16:29 volumio volumio[684]: info: sendMpdCommand playlistinfo took 46 milliseconds Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:29 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:29 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:29 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:29 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":140,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27833.mp3","trackType":"mp3"} Jul 04 23:16:29 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jul 04 23:16:29 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:29 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:29 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:29 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":140,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27833.mp3","trackType":"mp3"} Jul 04 23:16:29 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:29 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:29 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:29 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:29 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:29 volumio volumio[684]: info: ------------------------------ 370ms Jul 04 23:16:29 volumio volumio[684]: info: ------------------------------ 499ms Jul 04 23:16:29 volumio volumio[684]: info: sendMpdCommand playlistinfo took 385 milliseconds Jul 04 23:16:29 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:29 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:29 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:29 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:29 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":140,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27833.mp3","trackType":"mp3"} Jul 04 23:16:29 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:29 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:29 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:29 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:29 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:29 volumio volumio[684]: info: ------------------------------ 769ms Jul 04 23:16:33 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:33 volumio volumio[684]: info: Listing playlists Jul 04 23:16:33 volumio volumio[684]: info: Listing playlists Jul 04 23:16:36 volumio volumio[684]: info: Jul 04 23:16:36 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:36 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:36 volumio volumio[684]: info: Jul 04 23:16:36 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:36 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:36 volumio volumio[684]: info: Jul 04 23:16:36 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:36 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:36 volumio volumio[684]: info: sendMpdCommand status took 42 milliseconds Jul 04 23:16:36 volumio volumio[684]: info: sendMpdCommand status took 44 milliseconds Jul 04 23:16:36 volumio volumio[684]: info: sendMpdCommand status took 33 milliseconds Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:36 volumio volumio[684]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 04 23:16:36 volumio volumio[684]: info: sendMpdCommand playlistinfo took 31 milliseconds Jul 04 23:16:36 volumio volumio[684]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:36 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:36 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:36 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:36 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:36 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:37 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:37 volumio volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27833.mp3","trackType":"mp3"} Jul 04 23:16:37 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::syncState stateService stop Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::stPlaybackTimer Jul 04 23:16:37 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:37 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:37 volumio volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27833.mp3","trackType":"mp3"} Jul 04 23:16:37 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::syncState stateService stop Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:37 volumio volumio[684]: info: No code Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:37 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:37 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:37 volumio volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track01/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27833.mp3","trackType":"mp3"} Jul 04 23:16:37 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::syncState stateService stop Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:37 volumio volumio[684]: info: No code Jul 04 23:16:37 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:37 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:37 volumio volumio[684]: info: ------------------------------ 638ms Jul 04 23:16:37 volumio volumio[684]: info: ------------------------------ 628ms Jul 04 23:16:37 volumio volumio[684]: info: ------------------------------ 621ms Jul 04 23:16:38 volumio volumio[684]: info: Clearing queue after UPNP request Jul 04 23:16:38 volumio volumio[684]: info: CoreStateMachine::ClearQueue Jul 04 23:16:38 volumio volumio[684]: info: CoreStateMachine::stop Jul 04 23:16:38 volumio volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 23:16:38 volumio volumio[684]: info: CorePlayQueue::clearPlayQueue Jul 04 23:16:38 volumio volumio[684]: info: CorePlayQueue::saveQueue Jul 04 23:16:38 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:38 volumio volumio[684]: info: CoreCommandRouter::volumioPushQueue Jul 04 23:16:38 volumio volumio[684]: info: Jul 04 23:16:38 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:38 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:38 volumio volumio[684]: info: Jul 04 23:16:38 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:38 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:38 volumio volumio[684]: info: Jul 04 23:16:38 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:38 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:38 volumio volumio[684]: error: updateQueue error: null Jul 04 23:16:38 volumio volumio[684]: error: updateQueue error: null Jul 04 23:16:38 volumio volumio[684]: error: updateQueue error: null Jul 04 23:16:38 volumio volumio[684]: info: ------------------------------ 45ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 37ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 28ms Jul 04 23:16:39 volumio volumio[684]: info: Starting UPNP Playback Jul 04 23:16:39 volumio volumio[684]: info: Preparing playback through UPNP Jul 04 23:16:39 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:39 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:39 volumio volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 39ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 28ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 20ms Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 37ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 28ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 20ms Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 38ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 28ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 21ms Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio ntpd[579]: 85.215.189.120 local addr 192.168.1.76 -> Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 42ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 33ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 26ms Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 37ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 28ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 19ms Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: Jul 04 23:16:39 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:39 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 38ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 27ms Jul 04 23:16:39 volumio volumio[684]: info: ------------------------------ 20ms Jul 04 23:16:40 volumio volumio[684]: info: Jul 04 23:16:40 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:40 volumio volumio[684]: info: Jul 04 23:16:40 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:40 volumio volumio[684]: info: Jul 04 23:16:40 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:40 volumio volumio[684]: info: Jul 04 23:16:40 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:40 volumio volumio[684]: info: sendMpdCommand status took 93 milliseconds Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:40 volumio volumio[684]: info: sendMpdCommand status took 98 milliseconds Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:40 volumio volumio[684]: info: sendMpdCommand status took 118 milliseconds Jul 04 23:16:40 volumio volumio[684]: info: sendMpdCommand status took 94 milliseconds Jul 04 23:16:40 volumio volumio[684]: info: sendMpdCommand playlistinfo took 69 milliseconds Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:40 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:40 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track02/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:40 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jul 04 23:16:40 volumio volumio[684]: info: ------------------------------ 286ms Jul 04 23:16:40 volumio volumio[684]: info: Jul 04 23:16:40 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:40 volumio volumio[684]: info: Jul 04 23:16:40 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:40 volumio volumio[684]: info: sendMpdCommand playlistinfo took 173 milliseconds Jul 04 23:16:40 volumio volumio[684]: info: sendMpdCommand playlistinfo took 120 milliseconds Jul 04 23:16:40 volumio volumio[684]: info: sendMpdCommand playlistinfo took 121 milliseconds Jul 04 23:16:40 volumio volumio[684]: info: sendMpdCommand status took 60 milliseconds Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:40 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:40 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:40 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track02/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:40 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:40 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:40 volumio ntpd[579]: 116.202.100.11 local addr 192.168.1.76 -> Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:40 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:40 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track02/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:40 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:40 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:40 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:40 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:40 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track02/Bad Religion - Greatest Hits","artist":null,"album":"Bad Religion - Greatest Hits","uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:40 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:40 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:40 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:40 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:41 volumio volumio[684]: info: ------------------------------ 1240ms Jul 04 23:16:41 volumio volumio[684]: info: ------------------------------ 1223ms Jul 04 23:16:41 volumio volumio[684]: info: ------------------------------ 1205ms Jul 04 23:16:41 volumio volumio[684]: info: Jul 04 23:16:41 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:41 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:41 volumio volumio[684]: info: Jul 04 23:16:41 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:41 volumio volumio[684]: info: sendMpdCommand status took 1008 milliseconds Jul 04 23:16:41 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:41 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:41 volumio volumio[684]: info: sendMpdCommand playlistinfo took 1021 milliseconds Jul 04 23:16:41 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:41 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:41 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:41 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:41 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:41 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:41 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track02","artist":null,"album":null,"uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:41 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:41 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:41 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:41 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:41 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:41 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:41 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:41 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:41 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:41 volumio volumio[684]: info: ------------------------------ 1443ms Jul 04 23:16:42 volumio volumio[684]: info: Jul 04 23:16:42 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:42 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:42 volumio volumio[684]: info: Jul 04 23:16:42 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:42 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:42 volumio volumio[684]: info: Jul 04 23:16:42 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:42 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:42 volumio volumio[684]: info: Jul 04 23:16:42 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:42 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:42 volumio volumio[684]: info: ------------------------------ 689ms Jul 04 23:16:42 volumio volumio[684]: info: sendMpdCommand status took 660 milliseconds Jul 04 23:16:42 volumio volumio[684]: info: sendMpdCommand playlistinfo took 590 milliseconds Jul 04 23:16:42 volumio volumio[684]: info: ------------------------------ 133ms Jul 04 23:16:42 volumio volumio[684]: info: sendMpdCommand status took 115 milliseconds Jul 04 23:16:42 volumio volumio[684]: info: ------------------------------ 116ms Jul 04 23:16:42 volumio volumio[684]: info: sendMpdCommand status took 93 milliseconds Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:42 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:42 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:42 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track02","artist":null,"album":null,"uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:42 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:42 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:42 volumio volumio[684]: info: ------------------------------ 2019ms Jul 04 23:16:42 volumio volumio[684]: info: sendMpdCommand playlistinfo took 291 milliseconds Jul 04 23:16:42 volumio volumio[684]: info: sendMpdCommand playlistinfo took 283 milliseconds Jul 04 23:16:42 volumio volumio[684]: info: sendMpdCommand playlistinfo took 281 milliseconds Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:42 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:42 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:42 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:42 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track02","artist":null,"album":null,"uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:42 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:42 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:42 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:42 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:42 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2102,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track02","artist":null,"album":null,"uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:42 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:42 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:42 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:42 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:42 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2102,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Track02","artist":null,"album":null,"uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:42 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:42 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:42 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:42 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:43 volumio volumio[684]: info: ------------------------------ 1705ms Jul 04 23:16:43 volumio volumio[684]: info: ------------------------------ 1139ms Jul 04 23:16:43 volumio volumio[684]: info: ------------------------------ 1112ms Jul 04 23:16:43 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:45 volumio volumio[684]: info: Jul 04 23:16:45 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:45 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:45 volumio volumio[684]: info: Jul 04 23:16:45 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:45 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:45 volumio volumio[684]: info: Jul 04 23:16:45 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:45 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:45 volumio volumio[684]: info: sendMpdCommand status took 46 milliseconds Jul 04 23:16:45 volumio volumio[684]: info: sendMpdCommand status took 46 milliseconds Jul 04 23:16:45 volumio volumio[684]: info: sendMpdCommand status took 32 milliseconds Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:45 volumio volumio[684]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 04 23:16:45 volumio volumio[684]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 04 23:16:45 volumio volumio[684]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:45 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:45 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:45 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:45 volumio volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track02","artist":null,"album":null,"uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:45 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::syncState stateService stop Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::stPlaybackTimer Jul 04 23:16:45 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:45 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:45 volumio volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track02","artist":null,"album":null,"uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:45 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::syncState stateService stop Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:45 volumio volumio[684]: info: No code Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:45 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:45 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:45 volumio volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track02","artist":null,"album":null,"uri":"http://192.168.1.21:50002/m/MP3/27826.mp3","trackType":"mp3"} Jul 04 23:16:45 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::syncState stateService stop Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:45 volumio volumio[684]: info: No code Jul 04 23:16:45 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:45 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:45 volumio volumio[684]: info: ------------------------------ 771ms Jul 04 23:16:45 volumio volumio[684]: info: ------------------------------ 761ms Jul 04 23:16:45 volumio volumio[684]: info: ------------------------------ 753ms Jul 04 23:16:46 volumio volumio[684]: info: Clearing queue after UPNP request Jul 04 23:16:46 volumio volumio[684]: info: CoreStateMachine::ClearQueue Jul 04 23:16:46 volumio volumio[684]: info: CoreStateMachine::stop Jul 04 23:16:46 volumio volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 23:16:46 volumio volumio[684]: info: CorePlayQueue::clearPlayQueue Jul 04 23:16:46 volumio volumio[684]: info: CorePlayQueue::saveQueue Jul 04 23:16:46 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:46 volumio volumio[684]: info: CoreCommandRouter::volumioPushQueue Jul 04 23:16:46 volumio volumio[684]: info: Jul 04 23:16:46 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:46 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:46 volumio volumio[684]: info: Jul 04 23:16:46 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:46 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:46 volumio volumio[684]: info: Jul 04 23:16:46 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:46 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:46 volumio volumio[684]: error: updateQueue error: null Jul 04 23:16:46 volumio volumio[684]: error: updateQueue error: null Jul 04 23:16:46 volumio volumio[684]: error: updateQueue error: null Jul 04 23:16:46 volumio volumio[684]: info: ------------------------------ 43ms Jul 04 23:16:46 volumio volumio[684]: info: ------------------------------ 36ms Jul 04 23:16:46 volumio volumio[684]: info: ------------------------------ 28ms Jul 04 23:16:46 volumio volumio[684]: info: Starting UPNP Playback Jul 04 23:16:46 volumio volumio[684]: info: Preparing playback through UPNP Jul 04 23:16:46 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:46 volumio volumio[684]: info: CorePlayQueue::getTrack 0 Jul 04 23:16:46 volumio volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 39ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 27ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 19ms Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 36ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 27ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 19ms Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 52ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 28ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 20ms Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 36ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 26ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 19ms Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 37ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 27ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 19ms Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:47 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 37ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 27ms Jul 04 23:16:47 volumio volumio[684]: info: ------------------------------ 19ms Jul 04 23:16:47 volumio ntpd[579]: 144.76.0.164 local addr 192.168.1.76 -> Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:47 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:47 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:47 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:47 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:47 volumio volumio[684]: info: sendMpdCommand status took 93 milliseconds Jul 04 23:16:47 volumio volumio[684]: info: sendMpdCommand status took 94 milliseconds Jul 04 23:16:47 volumio volumio[684]: info: sendMpdCommand status took 73 milliseconds Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:47 volumio volumio[684]: info: Jul 04 23:16:47 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:47 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:47 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:48 volumio volumio[684]: info: Jul 04 23:16:48 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:48 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:48 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:48 volumio volumio[684]: info: sendMpdCommand status took 134 milliseconds Jul 04 23:16:48 volumio volumio[684]: info: sendMpdCommand playlistinfo took 88 milliseconds Jul 04 23:16:48 volumio volumio[684]: info: sendMpdCommand playlistinfo took 87 milliseconds Jul 04 23:16:48 volumio volumio[684]: info: sendMpdCommand playlistinfo took 75 milliseconds Jul 04 23:16:48 volumio volumio[684]: info: sendMpdCommand status took 60 milliseconds Jul 04 23:16:48 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:48 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:48 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:48 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:48 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:48 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:48 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:48 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:48 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:48 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Cochise/Audioslave - Audioslave","artist":"Audioslave","album":"Audioslave","uri":"http://192.168.1.21:50002/m/MP3/23789.mp3","trackType":"mp3"} Jul 04 23:16:48 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jul 04 23:16:48 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:48 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:48 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Cochise/Audioslave - Audioslave","artist":"Audioslave","album":"Audioslave","uri":"http://192.168.1.21:50002/m/MP3/23789.mp3","trackType":"mp3"} Jul 04 23:16:48 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:48 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:48 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:48 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:48 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Cochise/Audioslave - Audioslave","artist":"Audioslave","album":"Audioslave","uri":"http://192.168.1.21:50002/m/MP3/23789.mp3","trackType":"mp3"} Jul 04 23:16:48 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:48 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:48 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:48 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:48 volumio volumio[684]: info: ------------------------------ 1005ms Jul 04 23:16:49 volumio volumio[684]: info: ------------------------------ 1339ms Jul 04 23:16:49 volumio volumio[684]: info: ------------------------------ 1314ms Jul 04 23:16:49 volumio volumio[684]: info: Jul 04 23:16:49 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:49 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:49 volumio volumio[684]: info: Jul 04 23:16:49 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:49 volumio volumio[684]: info: sendMpdCommand status took 1181 milliseconds Jul 04 23:16:49 volumio volumio[684]: info: sendMpdCommand playlistinfo took 1137 milliseconds Jul 04 23:16:49 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:49 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:49 volumio volumio[684]: info: sendMpdCommand playlistinfo took 1125 milliseconds Jul 04 23:16:49 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:49 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:49 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:49 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:49 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:49 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:49 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:49 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Cochise/Audioslave - Audioslave","artist":"Audioslave","album":"Audioslave","uri":"http://192.168.1.21:50002/m/MP3/23789.mp3","trackType":"mp3"} Jul 04 23:16:49 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:49 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:49 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:49 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:49 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 04 23:16:49 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:49 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:49 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:49 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:49 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:49 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:49 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:49 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cochise","artist":"Audioslave","album":"Audioslave","uri":"http://192.168.1.21:50002/m/MP3/23789.mp3","trackType":"mp3"} Jul 04 23:16:49 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:49 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:49 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:49 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:49 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 04 23:16:49 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:49 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:49 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:49 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:49 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:50 volumio volumio[684]: info: ------------------------------ 2174ms Jul 04 23:16:50 volumio volumio[684]: info: ------------------------------ 2077ms Jul 04 23:16:50 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:50 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:50 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:50 volumio volumio[684]: info: Jul 04 23:16:50 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:50 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:50 volumio volumio[684]: info: Jul 04 23:16:50 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:50 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:50 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:50 volumio volumio[684]: info: Jul 04 23:16:50 volumio volumio[684]: ---------------------------- MPD announces system playlist update Jul 04 23:16:50 volumio volumio[684]: info: Ignoring MPD Status Update Jul 04 23:16:50 volumio volumio[684]: info: Jul 04 23:16:50 volumio volumio[684]: ---------------------------- MPD announces state update: player Jul 04 23:16:50 volumio volumio[684]: info: ControllerMpd::getState Jul 04 23:16:50 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jul 04 23:16:50 volumio volumio[684]: info: ------------------------------ 1687ms Jul 04 23:16:50 volumio volumio[684]: info: sendMpdCommand status took 1649 milliseconds Jul 04 23:16:50 volumio volumio[684]: info: sendMpdCommand playlistinfo took 1646 milliseconds Jul 04 23:16:50 volumio volumio[684]: info: ------------------------------ 234ms Jul 04 23:16:50 volumio volumio[684]: info: sendMpdCommand status took 181 milliseconds Jul 04 23:16:50 volumio volumio[684]: info: ------------------------------ 182ms Jul 04 23:16:50 volumio volumio[684]: info: sendMpdCommand status took 156 milliseconds Jul 04 23:16:50 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:50 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:50 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:50 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:50 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:50 volumio volumio[684]: verbose: ControllerMpd::parseState Jul 04 23:16:50 volumio volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 23:16:50 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:51 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:51 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cochise","artist":"Audioslave","album":"Audioslave","uri":"http://192.168.1.21:50002/m/MP3/23789.mp3","trackType":"mp3"} Jul 04 23:16:51 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:51 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:51 volumio volumio[684]: info: ------------------------------ 3453ms Jul 04 23:16:51 volumio volumio[684]: info: sendMpdCommand playlistinfo took 591 milliseconds Jul 04 23:16:51 volumio volumio[684]: info: sendMpdCommand playlistinfo took 585 milliseconds Jul 04 23:16:51 volumio volumio[684]: info: sendMpdCommand playlistinfo took 572 milliseconds Jul 04 23:16:51 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:51 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:51 volumio volumio[684]: verbose: ControllerMpd::parseTrackInfo Jul 04 23:16:51 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:51 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:51 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Cochise","artist":"Audioslave","album":"Audioslave","uri":"http://192.168.1.21:50002/m/MP3/23789.mp3","trackType":"mp3"} Jul 04 23:16:51 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:51 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:51 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:51 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:51 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3108,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Cochise","artist":"Audioslave","album":"Audioslave","uri":"http://192.168.1.21:50002/m/MP3/23789.mp3","trackType":"mp3"} Jul 04 23:16:51 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:51 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:51 volumio volumio[684]: info: ControllerMpd::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::servicePushState Jul 04 23:16:51 volumio volumio[684]: verbose: In UPNP mode Jul 04 23:16:51 volumio volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3108,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Cochise","artist":"Audioslave","album":"Audioslave","uri":"http://192.168.1.21:50002/m/MP3/23789.mp3","trackType":"mp3"} Jul 04 23:16:51 volumio volumio[684]: verbose: CURRENT POSITION 0 Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::syncState stateService play Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::syncState currentStatus play Jul 04 23:16:51 volumio volumio[684]: info: Received an update from plugin. extracting info from payload Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:51 volumio volumio[684]: info: CoreStateMachine::pushState Jul 04 23:16:51 volumio volumio[684]: info: CoreCommandRouter::volumioPushState Jul 04 23:16:51 volumio volumio[684]: info: ------------------------------ 2789ms Jul 04 23:16:51 volumio volumio[684]: info: ------------------------------ 1298ms Jul 04 23:16:52 volumio volumio[684]: info: ------------------------------ 1246ms Jul 04 23:16:52 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreCommandRouter::volumioGetQueue Jul 04 23:16:52 volumio volumio[684]: info: CoreStateMachine::getQueue Jul 04 23:16:52 volumio volumio[684]: info: CorePlayQueue::getQueue Jul 04 23:16:53 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:53 volumio volumio[684]: info: Listing playlists Jul 04 23:16:53 volumio volumio[684]: info: Listing playlists Jul 04 23:16:58 volumio volumio[684]: verbose: New Socket.io Connection to 192.168.1.76 from 192.168.1.22 UA: Mozilla/5.0 (Android 10; Mobile; rv:127.0) Gecko/127.0 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 4 Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::volumioGetVisibleSources Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 04 23:16:58 volumio volumio[684]: info: Received Get System Info Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 23:16:58 volumio volumio[684]: info: Discovery: Getting this device information Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 23:16:58 volumio volumio[684]: info: CoreCommandRouter::volumioGetState Jul 04 23:16:58 volumio volumio[684]: info: Listing playlists Jul 04 23:16:59 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 04 23:16:59 volumio volumio[684]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 23:17:00 volumio volumio[684]: Error: write EPROTO 3065918016:error:1408F10B:SSL routines:ssl3_get_record:wrong version number:../deps/openssl/openssl/ssl/record/ssl3_record.c:332: Jul 04 23:17:00 volumio volumio[684]: at WriteWrap.onWriteComplete [as oncomplete] (internal/stream_base_commons.js:94:16) { Jul 04 23:17:00 volumio volumio[684]: errno: -71, Jul 04 23:17:00 volumio volumio[684]: code: 'EPROTO', Jul 04 23:17:00 volumio volumio[684]: syscall: 'write' Jul 04 23:17:00 volumio volumio[684]: } Jul 04 23:17:00 volumio volumio[684]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 23:17:04 volumio sudo[3112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-04 23:16 Jul 04 23:17:04 volumio sudo[3112]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"