-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Fri 2025-10-24 05:47:20 UTC. -- Oct 24 05:46:00 volumio volumio[1008]: info: Oct 24 05:46:00 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:00 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:00 volumio volumio[1008]: info: Oct 24 05:46:00 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:00 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:00 volumio volumio[1008]: info: Oct 24 05:46:00 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:00 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:00 volumio volumio[1008]: info: Oct 24 05:46:00 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:00 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand status took 31 milliseconds Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand status took 28 milliseconds Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand status took 27 milliseconds Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:00 volumio volumio[1008]: info: Oct 24 05:46:00 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:00 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:00 volumio volumio[1008]: info: Oct 24 05:46:00 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:00 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand status took 74 milliseconds Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand clearerror took 61 milliseconds Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand clearerror took 58 milliseconds Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand clearerror took 55 milliseconds Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand status took 29 milliseconds Oct 24 05:46:00 volumio volumio[1008]: info: sendMpdCommand status took 26 milliseconds Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:00 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:00 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:00 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:00 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:00 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":159,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:00 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:00 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:00 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:00 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:00 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:00 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:00 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:00 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:00 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:00 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":159,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:00 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:00 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:00 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:00 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:00 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:00 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:00 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:00 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:00 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":159,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:00 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:00 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:00 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:00 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:00 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:00 volumio volumio[1008]: info: ------------------------------ 200ms Oct 24 05:46:00 volumio volumio[1008]: info: ------------------------------ 195ms Oct 24 05:46:00 volumio volumio[1008]: info: ------------------------------ 192ms Oct 24 05:46:01 volumio volumio[1008]: info: sendMpdCommand clearerror took 142 milliseconds Oct 24 05:46:01 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 132 milliseconds Oct 24 05:46:01 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 127 milliseconds Oct 24 05:46:01 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 127 milliseconds Oct 24 05:46:01 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:01 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:01 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:01 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:01 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:01 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:01 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":159,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:01 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:01 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:01 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:01 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:01 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:01 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:01 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:01 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:01 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":159,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:01 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:01 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:01 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:01 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:01 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:01 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:01 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:01 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:01 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":159,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:01 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:01 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:01 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:01 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:01 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:01 volumio volumio[1008]: info: ------------------------------ 292ms Oct 24 05:46:01 volumio volumio[1008]: info: ------------------------------ 242ms Oct 24 05:46:01 volumio volumio[1008]: info: ------------------------------ 238ms Oct 24 05:46:07 volumio volumio[1008]: info: Oct 24 05:46:07 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:07 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:07 volumio volumio[1008]: info: Oct 24 05:46:07 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:07 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:07 volumio volumio[1008]: info: Oct 24 05:46:07 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:07 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:07 volumio volumio[1008]: info: sendMpdCommand status took 32 milliseconds Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:07 volumio volumio[1008]: info: sendMpdCommand status took 29 milliseconds Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:07 volumio volumio[1008]: info: sendMpdCommand status took 24 milliseconds Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:07 volumio volumio[1008]: info: sendMpdCommand clearerror took 43 milliseconds Oct 24 05:46:07 volumio volumio[1008]: info: sendMpdCommand clearerror took 41 milliseconds Oct 24 05:46:07 volumio volumio[1008]: info: sendMpdCommand clearerror took 39 milliseconds Oct 24 05:46:07 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 24 05:46:07 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 24 05:46:07 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:07 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:07 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:07 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:07 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:07 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":159,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:07 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:07 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:07 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:07 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:07 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:07 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:07 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:07 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:07 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:07 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":159,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:07 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:07 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:07 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:07 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:07 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:07 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:07 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:07 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:07 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":159,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:07 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:07 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:07 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:07 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:07 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:07 volumio volumio[1008]: info: ------------------------------ 155ms Oct 24 05:46:07 volumio volumio[1008]: info: ------------------------------ 154ms Oct 24 05:46:07 volumio volumio[1008]: info: ------------------------------ 149ms Oct 24 05:46:14 volumio volumio[1008]: info: Oct 24 05:46:14 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:14 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:14 volumio volumio[1008]: info: Oct 24 05:46:14 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:14 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:14 volumio volumio[1008]: info: Oct 24 05:46:14 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:14 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:14 volumio volumio[1008]: info: sendMpdCommand status took 13 milliseconds Oct 24 05:46:14 volumio volumio[1008]: info: sendMpdCommand status took 10 milliseconds Oct 24 05:46:14 volumio volumio[1008]: info: sendMpdCommand status took 6 milliseconds Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:14 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 24 05:46:14 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 24 05:46:14 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:14 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:14 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:15 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Oct 24 05:46:15 volumio volumio[1008]: 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":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:15 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::syncState stateService stop Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:15 volumio volumio[1008]: info: No code Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:15 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:15 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Oct 24 05:46:15 volumio volumio[1008]: 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":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:15 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::syncState stateService stop Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:15 volumio volumio[1008]: info: No code Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:15 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:15 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Oct 24 05:46:15 volumio volumio[1008]: 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":"End of Beginning","artist":"Djo","album":"DECIDE","uri":"http://192.168.50.48:17938/tidal/246282420.flac","trackType":"tidal"} Oct 24 05:46:15 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::syncState stateService stop Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:15 volumio volumio[1008]: info: No code Oct 24 05:46:15 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:15 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:15 volumio volumio[1008]: info: ------------------------------ 193ms Oct 24 05:46:15 volumio volumio[1008]: info: ------------------------------ 188ms Oct 24 05:46:15 volumio volumio[1008]: info: ------------------------------ 185ms Oct 24 05:46:16 volumio volumio[1008]: info: Clearing queue after UPNP request Oct 24 05:46:16 volumio volumio[1008]: info: CoreStateMachine::ClearQueue Oct 24 05:46:16 volumio volumio[1008]: info: CoreStateMachine::stop Oct 24 05:46:16 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 05:46:16 volumio volumio[1008]: info: CorePlayQueue::clearPlayQueue Oct 24 05:46:16 volumio volumio[1008]: info: CorePlayQueue::saveQueue Oct 24 05:46:16 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:16 volumio volumio[1008]: info: CoreCommandRouter::volumioPushQueue Oct 24 05:46:16 volumio volumio[1008]: info: Oct 24 05:46:16 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:16 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:16 volumio volumio[1008]: info: Oct 24 05:46:16 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:16 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:16 volumio volumio[1008]: info: Oct 24 05:46:16 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:16 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:16 volumio volumio[1008]: error: updateQueue error: null Oct 24 05:46:16 volumio volumio[1008]: error: updateQueue error: null Oct 24 05:46:16 volumio volumio[1008]: error: updateQueue error: null Oct 24 05:46:16 volumio volumio[1008]: info: ------------------------------ 12ms Oct 24 05:46:16 volumio volumio[1008]: info: ------------------------------ 9ms Oct 24 05:46:16 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:16 volumio volumio[1008]: info: Starting UPNP Playback Oct 24 05:46:16 volumio volumio[1008]: info: Preparing playback through UPNP Oct 24 05:46:16 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Oct 24 05:46:16 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Oct 24 05:46:16 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 11ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 9ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 6ms Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 12ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 9ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 5ms Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 10ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 5ms Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 10ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 5ms Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 10ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 5ms Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:17 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 9ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:17 volumio volumio[1008]: info: ------------------------------ 5ms Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:17 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:17 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:17 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:17 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand status took 31 milliseconds Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand status took 30 milliseconds Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand status took 30 milliseconds Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:17 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:17 volumio volumio[1008]: info: Oct 24 05:46:17 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:17 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand status took 88 milliseconds Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand clearerror took 72 milliseconds Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand clearerror took 69 milliseconds Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand clearerror took 67 milliseconds Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 48 milliseconds Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand status took 23 milliseconds Oct 24 05:46:17 volumio volumio[1008]: info: sendMpdCommand status took 20 milliseconds Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:17 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:17 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:17 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:17 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:17 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:17 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:17 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:17 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:17 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:17 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:17 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:17 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:17 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:17 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:17 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:17 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:17 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:17 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:17 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:17 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:17 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:17 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:17 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:17 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:17 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:17 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:17 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:17 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:17 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:18 volumio volumio[1008]: info: ------------------------------ 194ms Oct 24 05:46:18 volumio volumio[1008]: info: ------------------------------ 191ms Oct 24 05:46:18 volumio volumio[1008]: info: ------------------------------ 190ms Oct 24 05:46:18 volumio volumio[1008]: info: sendMpdCommand clearerror took 116 milliseconds Oct 24 05:46:18 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 108 milliseconds Oct 24 05:46:18 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 105 milliseconds Oct 24 05:46:18 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 105 milliseconds Oct 24 05:46:18 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:18 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:18 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:18 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:18 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:18 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:18 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:18 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:18 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:18 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:18 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:18 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:18 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:18 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:18 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:18 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:18 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:18 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:18 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:18 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:18 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:18 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:18 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:18 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:18 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:18 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:18 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:18 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:18 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:18 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:18 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:18 volumio volumio[1008]: info: ------------------------------ 274ms Oct 24 05:46:18 volumio volumio[1008]: info: ------------------------------ 223ms Oct 24 05:46:18 volumio volumio[1008]: info: ------------------------------ 206ms Oct 24 05:46:20 volumio volumio[1008]: info: Oct 24 05:46:20 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:20 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:20 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:20 volumio volumio[1008]: info: Oct 24 05:46:20 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:20 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:20 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:20 volumio volumio[1008]: info: Oct 24 05:46:20 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:20 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:20 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:20 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:20 volumio volumio[1008]: info: sendMpdCommand status took 20 milliseconds Oct 24 05:46:20 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:20 volumio volumio[1008]: info: sendMpdCommand status took 19 milliseconds Oct 24 05:46:20 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:20 volumio volumio[1008]: info: sendMpdCommand status took 19 milliseconds Oct 24 05:46:20 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:20 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:21 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:21 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:21 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:21 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:21 volumio volumio[1008]: info: sendMpdCommand clearerror took 33 milliseconds Oct 24 05:46:21 volumio volumio[1008]: info: sendMpdCommand clearerror took 31 milliseconds Oct 24 05:46:21 volumio volumio[1008]: info: sendMpdCommand clearerror took 29 milliseconds Oct 24 05:46:21 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 24 05:46:21 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 24 05:46:21 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 24 05:46:21 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:21 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:21 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:21 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:21 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:21 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:21 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:21 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:21 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:21 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:21 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:21 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:21 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:21 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:21 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:21 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:21 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:21 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:21 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:21 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:21 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:21 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:21 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:21 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:21 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:21 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:21 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:21 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:21 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:21 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:21 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:21 volumio volumio[1008]: info: ------------------------------ 137ms Oct 24 05:46:21 volumio volumio[1008]: info: ------------------------------ 135ms Oct 24 05:46:21 volumio volumio[1008]: info: ------------------------------ 132ms Oct 24 05:46:35 volumio volumio[1008]: info: Oct 24 05:46:35 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:35 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:35 volumio volumio[1008]: info: Oct 24 05:46:35 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:35 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:35 volumio volumio[1008]: info: Oct 24 05:46:35 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:35 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:35 volumio volumio[1008]: info: sendMpdCommand status took 8 milliseconds Oct 24 05:46:35 volumio volumio[1008]: info: sendMpdCommand status took 6 milliseconds Oct 24 05:46:35 volumio volumio[1008]: info: sendMpdCommand status took 4 milliseconds Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:35 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 24 05:46:35 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 24 05:46:35 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:35 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:35 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:35 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Oct 24 05:46:35 volumio volumio[1008]: 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":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:35 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::syncState stateService stop Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:35 volumio volumio[1008]: info: No code Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:35 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:35 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Oct 24 05:46:35 volumio volumio[1008]: 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":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:35 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::syncState stateService stop Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:35 volumio volumio[1008]: info: No code Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:35 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:35 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Oct 24 05:46:35 volumio volumio[1008]: 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":"Both Sides Now","artist":"Emilia Jones","album":"CODA (Soundtrack from the Apple Original Film)","uri":"http://192.168.50.48:17938/tidal/193361884.flac","trackType":"tidal"} Oct 24 05:46:35 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::syncState stateService stop Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:35 volumio volumio[1008]: info: No code Oct 24 05:46:35 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:35 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:35 volumio volumio[1008]: info: ------------------------------ 184ms Oct 24 05:46:35 volumio volumio[1008]: info: ------------------------------ 183ms Oct 24 05:46:35 volumio volumio[1008]: info: ------------------------------ 181ms Oct 24 05:46:37 volumio volumio[1008]: info: Clearing queue after UPNP request Oct 24 05:46:37 volumio volumio[1008]: info: CoreStateMachine::ClearQueue Oct 24 05:46:37 volumio volumio[1008]: info: CoreStateMachine::stop Oct 24 05:46:37 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 05:46:37 volumio volumio[1008]: info: CorePlayQueue::clearPlayQueue Oct 24 05:46:37 volumio volumio[1008]: info: CorePlayQueue::saveQueue Oct 24 05:46:37 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:37 volumio volumio[1008]: info: CoreCommandRouter::volumioPushQueue Oct 24 05:46:37 volumio volumio[1008]: info: Oct 24 05:46:37 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:37 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:37 volumio volumio[1008]: info: Oct 24 05:46:37 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:37 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:37 volumio volumio[1008]: info: Oct 24 05:46:37 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:37 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:37 volumio volumio[1008]: error: updateQueue error: null Oct 24 05:46:37 volumio volumio[1008]: error: updateQueue error: null Oct 24 05:46:37 volumio volumio[1008]: error: updateQueue error: null Oct 24 05:46:37 volumio volumio[1008]: info: ------------------------------ 10ms Oct 24 05:46:37 volumio volumio[1008]: info: ------------------------------ 9ms Oct 24 05:46:37 volumio volumio[1008]: info: ------------------------------ 6ms Oct 24 05:46:37 volumio volumio[1008]: info: Starting UPNP Playback Oct 24 05:46:37 volumio volumio[1008]: info: Preparing playback through UPNP Oct 24 05:46:37 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Oct 24 05:46:37 volumio volumio[1008]: info: CorePlayQueue::getTrack 0 Oct 24 05:46:37 volumio volumio[1008]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 11ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 8ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 10ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 5ms Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 9ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 5ms Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 8ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 4ms Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 14ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 12ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 6ms Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: Oct 24 05:46:38 volumio volumio[1008]: ---------------------------- MPD announces system playlist update Oct 24 05:46:38 volumio volumio[1008]: info: Ignoring MPD Status Update Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 9ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 7ms Oct 24 05:46:38 volumio volumio[1008]: info: ------------------------------ 5ms Oct 24 05:46:39 volumio volumio[1008]: info: Oct 24 05:46:39 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:39 volumio volumio[1008]: info: Oct 24 05:46:39 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:39 volumio volumio[1008]: info: Oct 24 05:46:39 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:39 volumio volumio[1008]: info: Oct 24 05:46:39 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand status took 20 milliseconds Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand status took 20 milliseconds Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand status took 19 milliseconds Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:39 volumio volumio[1008]: info: Oct 24 05:46:39 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:39 volumio volumio[1008]: info: Oct 24 05:46:39 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand status took 52 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand clearerror took 44 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand clearerror took 41 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand clearerror took 38 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand status took 25 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand status took 23 milliseconds Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:39 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:39 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:39 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:39 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:39 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:39 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:39 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:39 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:39 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:39 volumio volumio[1008]: info: ------------------------------ 147ms Oct 24 05:46:39 volumio volumio[1008]: info: ------------------------------ 144ms Oct 24 05:46:39 volumio volumio[1008]: info: ------------------------------ 140ms Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand clearerror took 103 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 91 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 90 milliseconds Oct 24 05:46:39 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 97 milliseconds Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:39 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:39 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:39 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:39 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:39 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:39 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:39 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:39 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:39 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:39 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:39 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:39 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:39 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:40 volumio volumio[1008]: info: ------------------------------ 273ms Oct 24 05:46:40 volumio volumio[1008]: info: ------------------------------ 239ms Oct 24 05:46:40 volumio volumio[1008]: info: ------------------------------ 238ms Oct 24 05:46:41 volumio volumio[1008]: info: Oct 24 05:46:41 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:41 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:41 volumio volumio[1008]: info: Oct 24 05:46:41 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:41 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:41 volumio volumio[1008]: info: Oct 24 05:46:41 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:41 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:41 volumio volumio[1008]: info: sendMpdCommand status took 17 milliseconds Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:41 volumio volumio[1008]: info: sendMpdCommand status took 16 milliseconds Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:41 volumio volumio[1008]: info: sendMpdCommand status took 16 milliseconds Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:41 volumio volumio[1008]: info: sendMpdCommand clearerror took 34 milliseconds Oct 24 05:46:41 volumio volumio[1008]: info: sendMpdCommand clearerror took 33 milliseconds Oct 24 05:46:41 volumio volumio[1008]: info: sendMpdCommand clearerror took 31 milliseconds Oct 24 05:46:41 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 24 05:46:41 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 24 05:46:41 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:41 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:41 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:41 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:41 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:41 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:41 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:41 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:41 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:41 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:41 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:41 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:41 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:41 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:41 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:41 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:41 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:41 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:41 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:41 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:41 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:41 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:41 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:41 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:41 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:41 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:41 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:41 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:41 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:41 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:41 volumio volumio[1008]: info: ------------------------------ 125ms Oct 24 05:46:41 volumio volumio[1008]: info: ------------------------------ 123ms Oct 24 05:46:41 volumio volumio[1008]: info: ------------------------------ 121ms Oct 24 05:46:42 volumio volumio[1008]: info: Oct 24 05:46:42 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:42 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:42 volumio volumio[1008]: info: Oct 24 05:46:42 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:42 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:42 volumio volumio[1008]: info: Oct 24 05:46:42 volumio volumio[1008]: ---------------------------- MPD announces state update: player Oct 24 05:46:42 volumio volumio[1008]: info: ControllerMpd::getState Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:42 volumio volumio[1008]: info: sendMpdCommand status took 22 milliseconds Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:42 volumio volumio[1008]: info: sendMpdCommand status took 21 milliseconds Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 24 05:46:42 volumio volumio[1008]: info: sendMpdCommand status took 20 milliseconds Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::parseState Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 05:46:42 volumio volumio[1008]: info: sendMpdCommand clearerror took 35 milliseconds Oct 24 05:46:42 volumio volumio[1008]: info: sendMpdCommand clearerror took 33 milliseconds Oct 24 05:46:42 volumio volumio[1008]: info: sendMpdCommand clearerror took 30 milliseconds Oct 24 05:46:42 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 24 05:46:42 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 24 05:46:42 volumio volumio[1008]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:42 volumio volumio[1008]: verbose: ControllerMpd::parseTrackInfo Oct 24 05:46:42 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:42 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:42 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:42 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:42 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:42 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:42 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:42 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:42 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 05:46:42 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:42 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:42 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:42 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:42 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:42 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:42 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:42 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:42 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:42 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:42 volumio volumio[1008]: info: ControllerMpd::pushState Oct 24 05:46:42 volumio volumio[1008]: info: CoreCommandRouter::servicePushState Oct 24 05:46:42 volumio volumio[1008]: verbose: In UPNP mode Oct 24 05:46:42 volumio volumio[1008]: verbose: STATE SERVICE {"status":"pause","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":"Reckless","artist":"Madison Beer","album":"Reckless","uri":"http://192.168.50.48:17938/tidal/185642909.flac","trackType":"tidal"} Oct 24 05:46:42 volumio volumio[1008]: verbose: CURRENT POSITION 0 Oct 24 05:46:42 volumio volumio[1008]: info: CoreStateMachine::syncState stateService pause Oct 24 05:46:42 volumio volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Oct 24 05:46:42 volumio volumio[1008]: info: CoreStateMachine::pushState Oct 24 05:46:42 volumio volumio[1008]: info: CoreCommandRouter::volumioPushState Oct 24 05:46:42 volumio volumio[1008]: info: ------------------------------ 161ms Oct 24 05:46:42 volumio volumio[1008]: info: ------------------------------ 158ms Oct 24 05:46:42 volumio volumio[1008]: info: ------------------------------ 156ms Oct 24 05:46:56 volumio volumio[1008]: verbose: New Socket.io Connection to 192.168.50.150 from 192.168.50.48 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/141.0.7390.96 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 05:46:56 volumio volumio[1008]: info: Received Get System Info Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 05:46:56 volumio volumio[1008]: info: Discovery: Getting this device information Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::volumioGetState Oct 24 05:46:56 volumio volumio[1008]: info: Listing playlists Oct 24 05:46:56 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 24 05:46:58 volumio volumio[1642]: [Vollibrespot] : couldn't parse packet from 192.168.50.48:5353: query type 65 is invalid Oct 24 05:46:59 volumio volumio[1642]: [Vollibrespot] : couldn't parse packet from 192.168.50.48:5353: query type 65 is invalid Oct 24 05:46:59 volumio volumio[1008]: info: CoreCommandRouter::volumioGetQueue Oct 24 05:46:59 volumio volumio[1008]: info: CoreStateMachine::getQueue Oct 24 05:46:59 volumio volumio[1008]: info: CorePlayQueue::getQueue Oct 24 05:47:01 volumio volumio[1008]: info: Preload queue cleared Oct 24 05:47:04 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 24 05:47:04 volumio volumio[1008]: info: CURURI: music-library Oct 24 05:47:04 volumio volumio[1008]: error: Failed LSINFO: null Oct 24 05:47:04 volumio volumio[1008]: info: Preload queue cleared Oct 24 05:47:05 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 24 05:47:05 volumio volumio[1008]: info: CURURI: playlists Oct 24 05:47:05 volumio volumio[1008]: info: Listing playlists Oct 24 05:47:06 volumio volumio[1008]: info: Preload queue cleared Oct 24 05:47:09 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 05:47:09 volumio volumio[1008]: info: Preload queue cleared Oct 24 05:47:10 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 05:47:10 volumio volumio[1008]: info: Preload queue cleared Oct 24 05:47:10 volumio volumio[1642]: [Vollibrespot] : couldn't parse packet from 192.168.50.48:5353: query type 65 is invalid Oct 24 05:47:10 volumio volumio[1642]: [Vollibrespot] : couldn't parse packet from 192.168.50.48:5353: query type 65 is invalid Oct 24 05:47:11 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 05:47:11 volumio volumio[1642]: [Vollibrespot] : couldn't parse packet from 192.168.50.48:5353: query type 65 is invalid Oct 24 05:47:15 volumio volumio[1008]: info: Preload queue cleared Oct 24 05:47:15 volumio volumio[1642]: [Vollibrespot] : couldn't parse packet from 192.168.50.48:5353: query type 65 is invalid Oct 24 05:47:16 volumio volumio[1642]: [Vollibrespot] : couldn't parse packet from 192.168.50.48:5353: query type 65 is invalid Oct 24 05:47:17 volumio volumio[1642]: [Vollibrespot] : couldn't parse packet from 192.168.50.48:5353: query type 65 is invalid Oct 24 05:47:17 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 05:47:19 volumio volumio[1008]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 05:47:19 volumio volumio[1008]: TypeError: Cannot read property 'length' of undefined Oct 24 05:47:19 volumio volumio[1008]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 24 05:47:19 volumio volumio[1008]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 24 05:47:19 volumio volumio[1008]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 24 05:47:19 volumio volumio[1008]: at Parser.emit (events.js:315:20) Oct 24 05:47:19 volumio volumio[1008]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 24 05:47:19 volumio volumio[1008]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 24 05:47:19 volumio volumio[1008]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 24 05:47:19 volumio volumio[1008]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 24 05:47:19 volumio volumio[1008]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 24 05:47:19 volumio volumio[1008]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 24 05:47:19 volumio volumio[1008]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 24 05:47:19 volumio volumio[1008]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 24 05:47:19 volumio volumio[1008]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 24 05:47:19 volumio volumio[1008]: at IncomingMessage.emit (events.js:327:22) Oct 24 05:47:19 volumio volumio[1008]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 24 05:47:19 volumio volumio[1008]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 24 05:47:19 volumio volumio[1008]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 05:47:20 volumio sudo[2237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 05:46 Oct 24 05:47:20 volumio sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"