-- Logs begin at Sat 2024-11-30 07:14:12 CST, end at Sat 2024-11-30 08:18:46 CST. -- Nov 30 08:17:01 volumio volumio[862]: info: Nov 30 08:17:01 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:01 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:01 volumio volumio[862]: info: Nov 30 08:17:01 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:01 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:01 volumio volumio[862]: info: Nov 30 08:17:01 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:01 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:01 volumio volumio[862]: info: sendMpdCommand status took 3 milliseconds Nov 30 08:17:01 volumio volumio[862]: info: sendMpdCommand status took 3 milliseconds Nov 30 08:17:01 volumio volumio[862]: info: sendMpdCommand status took 4 milliseconds Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:01 volumio volumio[862]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 08:17:01 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:17:01 volumio volumio[862]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:01 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:01 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:01 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:01 volumio volumio[862]: 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":"TRADE (Slow)","artist":"VodKe/无","album":"TRADE (Slow)","uri":"http://m802.music.126.net/20241130084138/e4cf85d24f7e90ab9bbe8f07b561877e/jd-musicrep-ts/cbe5/d2cd/5ca8/21cdee9b1f6ced9f3869cf0764d8dcae.mp3","trackType":"mp3"} Nov 30 08:17:01 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Nov 30 08:17:01 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:01 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:01 volumio volumio[862]: 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":"TRADE (Slow)","artist":"VodKe/无","album":"TRADE (Slow)","uri":"http://m802.music.126.net/20241130084138/e4cf85d24f7e90ab9bbe8f07b561877e/jd-musicrep-ts/cbe5/d2cd/5ca8/21cdee9b1f6ced9f3869cf0764d8dcae.mp3","trackType":"mp3"} Nov 30 08:17:01 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:01 volumio volumio[862]: info: No code Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:01 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:01 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:01 volumio volumio[862]: 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":"TRADE (Slow)","artist":"VodKe/无","album":"TRADE (Slow)","uri":"http://m802.music.126.net/20241130084138/e4cf85d24f7e90ab9bbe8f07b561877e/jd-musicrep-ts/cbe5/d2cd/5ca8/21cdee9b1f6ced9f3869cf0764d8dcae.mp3","trackType":"mp3"} Nov 30 08:17:01 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:01 volumio volumio[862]: info: No code Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:01 volumio volumio[862]: info: ------------------------------ 88ms Nov 30 08:17:01 volumio volumio[862]: info: ------------------------------ 88ms Nov 30 08:17:01 volumio volumio[862]: info: ------------------------------ 87ms Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:01 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:01 volumio volumio[862]: info: Clearing queue after UPNP request Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::ClearQueue Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::stop Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 30 08:17:01 volumio volumio[862]: info: CorePlayQueue::clearPlayQueue Nov 30 08:17:01 volumio volumio[862]: info: CorePlayQueue::saveQueue Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue Nov 30 08:17:01 volumio volumio[862]: info: Nov 30 08:17:01 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:01 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:01 volumio volumio[862]: info: Nov 30 08:17:01 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:01 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:01 volumio volumio[862]: info: Nov 30 08:17:01 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:01 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:01 volumio volumio[862]: error: updateQueue error: null Nov 30 08:17:01 volumio volumio[862]: info: ------------------------------ 6ms Nov 30 08:17:01 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:01 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:01 volumio volumio[862]: info: Starting UPNP Playback Nov 30 08:17:01 volumio volumio[862]: info: Preparing playback through UPNP Nov 30 08:17:01 volumio volumio[862]: info: CoreCommandRouter::volumioGetState Nov 30 08:17:01 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:01 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 2ms Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 7ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:02 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand status took 10 milliseconds Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand status took 10 milliseconds Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand status took 6 milliseconds Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:02 volumio volumio[862]: info: Nov 30 08:17:02 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand status took 26 milliseconds Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand status took 13 milliseconds Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:02 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:02 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":69,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pозовое вино","artist":"BABYKEITH","album":"Pозовое вино","uri":"http://m801.music.126.net/20241130084201/94f95aba7a4d3cb2d5651bede74f350d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17523538687/4cdf/b891/42f8/4d036948c7d920f6c16dc70c3df5bc4e.m4a","trackType":"m4a"} Nov 30 08:17:02 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:02 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:02 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":69,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pозовое вино","artist":"BABYKEITH","album":"Pозовое вино","uri":"http://m801.music.126.net/20241130084201/94f95aba7a4d3cb2d5651bede74f350d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17523538687/4cdf/b891/42f8/4d036948c7d920f6c16dc70c3df5bc4e.m4a","trackType":"m4a"} Nov 30 08:17:02 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:02 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:02 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:02 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":69,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pозовое вино","artist":"BABYKEITH","album":"Pозовое вино","uri":"http://m801.music.126.net/20241130084201/94f95aba7a4d3cb2d5651bede74f350d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17523538687/4cdf/b891/42f8/4d036948c7d920f6c16dc70c3df5bc4e.m4a","trackType":"m4a"} Nov 30 08:17:02 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:02 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 78ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 131ms Nov 30 08:17:02 volumio volumio[862]: info: ------------------------------ 128ms Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand status took 100 milliseconds Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand playlistinfo took 87 milliseconds Nov 30 08:17:02 volumio volumio[862]: info: sendMpdCommand playlistinfo took 87 milliseconds Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:02 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:02 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:02 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":69,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pозовое вино","artist":"BABYKEITH","album":"Pозовое вино","uri":"http://m801.music.126.net/20241130084201/94f95aba7a4d3cb2d5651bede74f350d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17523538687/4cdf/b891/42f8/4d036948c7d920f6c16dc70c3df5bc4e.m4a","trackType":"m4a"} Nov 30 08:17:02 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:02 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:02 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:02 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:02 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":69,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pозовое вино","artist":"BABYKEITH","album":"Pозовое вино","uri":"http://m801.music.126.net/20241130084201/94f95aba7a4d3cb2d5651bede74f350d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17523538687/4cdf/b891/42f8/4d036948c7d920f6c16dc70c3df5bc4e.m4a","trackType":"m4a"} Nov 30 08:17:02 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:02 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:02 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:02 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: ------------------------------ 182ms Nov 30 08:17:03 volumio volumio[862]: info: ------------------------------ 173ms Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: sendMpdCommand playlistinfo took 81 milliseconds Nov 30 08:17:03 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:03 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:03 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:03 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":69,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pозовое вино","artist":"BABYKEITH","album":"Pозовое вино","uri":"http://m801.music.126.net/20241130084201/94f95aba7a4d3cb2d5651bede74f350d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/17523538687/4cdf/b891/42f8/4d036948c7d920f6c16dc70c3df5bc4e.m4a","trackType":"m4a"} Nov 30 08:17:03 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:03 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: ------------------------------ 218ms Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:03 volumio volumio[862]: info: Nov 30 08:17:03 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:03 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:03 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:03 volumio volumio[862]: info: Nov 30 08:17:03 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:03 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:03 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:03 volumio volumio[862]: info: Nov 30 08:17:03 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:03 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:03 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:03 volumio volumio[862]: info: sendMpdCommand status took 3 milliseconds Nov 30 08:17:03 volumio volumio[862]: info: sendMpdCommand status took 2 milliseconds Nov 30 08:17:03 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:03 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:03 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:03 volumio volumio[862]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 30 08:17:03 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Nov 30 08:17:03 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:03 volumio volumio[862]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 30 08:17:03 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: No code Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: ------------------------------ 54ms Nov 30 08:17:03 volumio volumio[862]: info: sendMpdCommand status took 53 milliseconds Nov 30 08:17:03 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:03 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:03 volumio volumio[862]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 30 08:17:03 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: No code Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:03 volumio volumio[862]: info: ------------------------------ 79ms Nov 30 08:17:03 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:03 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:03 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:08 volumio volumio[862]: info: Clearing queue after UPNP request Nov 30 08:17:08 volumio volumio[862]: info: CoreStateMachine::ClearQueue Nov 30 08:17:08 volumio volumio[862]: info: CoreStateMachine::stop Nov 30 08:17:08 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 30 08:17:08 volumio volumio[862]: info: CorePlayQueue::clearPlayQueue Nov 30 08:17:08 volumio volumio[862]: info: CorePlayQueue::saveQueue Nov 30 08:17:08 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:08 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue Nov 30 08:17:08 volumio volumio[862]: info: Nov 30 08:17:08 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:08 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:08 volumio volumio[862]: info: Nov 30 08:17:08 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:08 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:08 volumio volumio[862]: info: Nov 30 08:17:08 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:08 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:08 volumio volumio[862]: error: updateQueue error: null Nov 30 08:17:08 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:08 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:08 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:08 volumio volumio[862]: info: Starting UPNP Playback Nov 30 08:17:08 volumio volumio[862]: info: Preparing playback through UPNP Nov 30 08:17:08 volumio volumio[862]: info: CoreCommandRouter::volumioGetState Nov 30 08:17:08 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:08 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 2ms Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 6ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 6ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 2ms Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:09 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 6ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 2ms Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand status took 5 milliseconds Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand status took 7 milliseconds Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand status took 8 milliseconds Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand status took 5 milliseconds Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:09 volumio volumio[862]: info: Nov 30 08:17:09 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand playlistinfo took 17 milliseconds Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand status took 5 milliseconds Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand status took 3 milliseconds Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:09 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:09 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:09 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:09 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:09 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:09 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:09 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:09 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:09 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:09 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:09 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:09 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:09 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:09 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:09 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 55ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 106ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 105ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 104ms Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand playlistinfo took 78 milliseconds Nov 30 08:17:09 volumio volumio[862]: info: sendMpdCommand playlistinfo took 79 milliseconds Nov 30 08:17:09 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:09 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:09 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:09 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:09 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:09 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:09 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:09 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:09 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 162ms Nov 30 08:17:09 volumio volumio[862]: info: ------------------------------ 169ms Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:09 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:09 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:15 volumio volumio[862]: info: Nov 30 08:17:15 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:15 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:15 volumio volumio[862]: info: Nov 30 08:17:15 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:15 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:15 volumio volumio[862]: info: Nov 30 08:17:15 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:15 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:15 volumio volumio[862]: info: sendMpdCommand status took 16 milliseconds Nov 30 08:17:15 volumio volumio[862]: info: sendMpdCommand status took 16 milliseconds Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:15 volumio volumio[862]: info: sendMpdCommand status took 22 milliseconds Nov 30 08:17:15 volumio volumio[862]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:15 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:15 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:15 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57000,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:15 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:15 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:15 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:15 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:15 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57000,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:15 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:15 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:15 volumio volumio[862]: info: ------------------------------ 97ms Nov 30 08:17:15 volumio volumio[862]: info: ------------------------------ 98ms Nov 30 08:17:15 volumio volumio[862]: info: sendMpdCommand playlistinfo took 77 milliseconds Nov 30 08:17:15 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:15 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:15 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:15 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57000,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:15 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:15 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:15 volumio volumio[862]: info: ------------------------------ 151ms Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:15 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:15 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:16 volumio volumio[862]: info: Nov 30 08:17:16 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:16 volumio volumio[862]: info: Nov 30 08:17:16 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:16 volumio volumio[862]: info: Nov 30 08:17:16 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:16 volumio volumio[862]: info: Nov 30 08:17:16 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:16 volumio volumio[862]: info: sendMpdCommand status took 6 milliseconds Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:16 volumio volumio[862]: info: sendMpdCommand status took 3 milliseconds Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:16 volumio volumio[862]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 30 08:17:16 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Nov 30 08:17:16 volumio volumio[862]: info: ------------------------------ 24ms Nov 30 08:17:16 volumio volumio[862]: info: Nov 30 08:17:16 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:16 volumio volumio[862]: info: Nov 30 08:17:16 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:16 volumio volumio[862]: info: sendMpdCommand status took 26 milliseconds Nov 30 08:17:16 volumio volumio[862]: info: sendMpdCommand status took 24 milliseconds Nov 30 08:17:16 volumio volumio[862]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 30 08:17:16 volumio volumio[862]: info: sendMpdCommand status took 3 milliseconds Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:16 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:16 volumio volumio[862]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 30 08:17:16 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: No code Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:16 volumio volumio[862]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 30 08:17:16 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: No code Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:16 volumio volumio[862]: 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":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084208/a771c9db0f35d15f15516904c1b5d414/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:16 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: No code Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:16 volumio volumio[862]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 30 08:17:16 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: No code Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:16 volumio volumio[862]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 30 08:17:16 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:16 volumio volumio[862]: info: No code Nov 30 08:17:16 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:16 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:17 volumio volumio[862]: info: ------------------------------ 179ms Nov 30 08:17:17 volumio volumio[862]: info: ------------------------------ 178ms Nov 30 08:17:17 volumio volumio[862]: info: ------------------------------ 184ms Nov 30 08:17:17 volumio volumio[862]: info: ------------------------------ 159ms Nov 30 08:17:17 volumio volumio[862]: info: ------------------------------ 158ms Nov 30 08:17:17 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:17 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:17 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:22 volumio volumio[862]: info: Clearing queue after UPNP request Nov 30 08:17:22 volumio volumio[862]: info: CoreStateMachine::ClearQueue Nov 30 08:17:22 volumio volumio[862]: info: CoreStateMachine::stop Nov 30 08:17:22 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 30 08:17:22 volumio volumio[862]: info: CorePlayQueue::clearPlayQueue Nov 30 08:17:22 volumio volumio[862]: info: CorePlayQueue::saveQueue Nov 30 08:17:22 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:22 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: error: updateQueue error: null Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:22 volumio volumio[862]: info: Starting UPNP Playback Nov 30 08:17:22 volumio volumio[862]: info: Preparing playback through UPNP Nov 30 08:17:22 volumio volumio[862]: info: CoreCommandRouter::volumioGetState Nov 30 08:17:22 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Nov 30 08:17:22 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 9ms Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 8ms Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 7ms Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: Nov 30 08:17:22 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:22 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 6ms Nov 30 08:17:22 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:23 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 5ms Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:23 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:23 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 4ms Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 2ms Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 2ms Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:23 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:23 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces system playlist update Nov 30 08:17:23 volumio volumio[862]: info: Ignoring MPD Status Update Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 3ms Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 2ms Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 1ms Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand status took 10 milliseconds Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand status took 11 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand status took 10 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand status took 10 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:23 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:23 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:23 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 23ms Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand playlistinfo took 11 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand playlistinfo took 11 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand playlistinfo took 12 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand status took 9 milliseconds Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:23 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:23 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:23 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:23 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:23 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:23 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:23 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:23 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:23 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:23 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:23 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:23 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 148ms Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 151ms Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 153ms Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand status took 136 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand playlistinfo took 125 milliseconds Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:23 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:23 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:23 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:23 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 215ms Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand playlistinfo took 111 milliseconds Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:23 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:23 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:23 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:23 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 309ms Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:23 volumio volumio[862]: info: Nov 30 08:17:23 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand status took 9 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand status took 6 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand status took 6 milliseconds Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:17:23 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:23 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:23 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:23 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1605,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:23 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:23 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:23 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1605,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:23 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Nov 30 08:17:23 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:23 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:23 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1605,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:23 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 44ms Nov 30 08:17:23 volumio volumio[862]: info: ------------------------------ 42ms Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:23 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:23 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:24 volumio volumio[862]: info: Nov 30 08:17:24 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:24 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:24 volumio volumio[862]: info: Nov 30 08:17:24 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:24 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:24 volumio volumio[862]: info: Nov 30 08:17:24 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:17:24 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:17:24 volumio volumio[862]: info: sendMpdCommand status took 5 milliseconds Nov 30 08:17:24 volumio volumio[862]: info: sendMpdCommand status took 3 milliseconds Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:24 volumio volumio[862]: info: sendMpdCommand status took 5 milliseconds Nov 30 08:17:24 volumio volumio[862]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 08:17:24 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:24 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:24 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:24 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2469,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:24 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:24 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:24 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:24 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:24 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2469,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:24 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:24 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:24 volumio volumio[862]: info: ------------------------------ 60ms Nov 30 08:17:24 volumio volumio[862]: info: ------------------------------ 59ms Nov 30 08:17:24 volumio volumio[862]: info: sendMpdCommand playlistinfo took 50 milliseconds Nov 30 08:17:24 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:17:24 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:17:24 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:17:24 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2469,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:17:24 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:17:24 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:17:24 volumio volumio[862]: info: ------------------------------ 83ms Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:17:24 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:17:24 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:06 volumio volumio[862]: info: Nov 30 08:18:06 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:18:06 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:18:06 volumio volumio[862]: info: Nov 30 08:18:06 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:18:06 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:18:06 volumio volumio[862]: info: Nov 30 08:18:06 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:18:06 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:18:06 volumio volumio[862]: info: sendMpdCommand status took 3 milliseconds Nov 30 08:18:06 volumio volumio[862]: info: sendMpdCommand status took 2 milliseconds Nov 30 08:18:06 volumio volumio[862]: info: sendMpdCommand status took 1 milliseconds Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:18:06 volumio volumio[862]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 30 08:18:06 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:18:06 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:18:06 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:18:06 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:18:06 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:18:06 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44599,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:18:06 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Nov 30 08:18:06 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:18:06 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:18:06 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44599,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:18:06 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Nov 30 08:18:06 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:18:06 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:18:06 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44599,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:18:06 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Nov 30 08:18:06 volumio volumio[862]: info: ------------------------------ 38ms Nov 30 08:18:06 volumio volumio[862]: info: ------------------------------ 38ms Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:18:06 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:18:06 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:06 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:18:06 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:18:06 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:31 volumio volumio[862]: info: Nov 30 08:18:31 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:18:31 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:18:31 volumio volumio[862]: info: Nov 30 08:18:31 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:18:31 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:18:31 volumio volumio[862]: info: Nov 30 08:18:31 volumio volumio[862]: ---------------------------- MPD announces state update: player Nov 30 08:18:31 volumio volumio[862]: info: ControllerMpd::getState Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Nov 30 08:18:31 volumio volumio[862]: info: sendMpdCommand status took 4 milliseconds Nov 30 08:18:31 volumio volumio[862]: info: sendMpdCommand status took 4 milliseconds Nov 30 08:18:31 volumio volumio[862]: info: sendMpdCommand status took 2 milliseconds Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::parseState Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 08:18:31 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:18:31 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:18:31 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:18:31 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Nov 30 08:18:31 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:18:31 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:18:31 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45464,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:18:31 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:18:31 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:18:31 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:18:31 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:18:31 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45464,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:18:31 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:18:31 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:18:31 volumio volumio[862]: info: ControllerMpd::pushState Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::servicePushState Nov 30 08:18:31 volumio volumio[862]: verbose: In UPNP mode Nov 30 08:18:31 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45464,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"3 2 1 ❀","artist":"VodKa/HSHK","album":"3 2 1 ❀","uri":"http://m801.music.126.net/20241130084222/1b7ffeeb6ed76fd91be6c0225d79d38a/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/10241484770/bb78/07db/d730/c9f74081385c2497a14ddb50f4679fab.m4a","trackType":"m4a"} Nov 30 08:18:31 volumio volumio[862]: verbose: CURRENT POSITION 0 Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::syncState stateService play Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus play Nov 30 08:18:31 volumio volumio[862]: info: Received an update from plugin. extracting info from payload Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::pushState Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Nov 30 08:18:31 volumio volumio[862]: info: ------------------------------ 80ms Nov 30 08:18:31 volumio volumio[862]: info: ------------------------------ 81ms Nov 30 08:18:31 volumio volumio[862]: info: ------------------------------ 81ms Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue Nov 30 08:18:31 volumio volumio[862]: info: CoreStateMachine::getQueue Nov 30 08:18:31 volumio volumio[862]: info: CorePlayQueue::getQueue Nov 30 08:18:46 volumio volumio[862]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 08:18:46 volumio volumio[862]: Error: connect ETIMEDOUT 66.220.146.94:443 Nov 30 08:18:46 volumio volumio[862]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 30 08:18:46 volumio volumio[862]: errno: -110, Nov 30 08:18:46 volumio volumio[862]: code: 'ETIMEDOUT', Nov 30 08:18:46 volumio volumio[862]: syscall: 'connect', Nov 30 08:18:46 volumio volumio[862]: address: '66.220.146.94', Nov 30 08:18:46 volumio volumio[862]: port: 443 Nov 30 08:18:46 volumio volumio[862]: } Nov 30 08:18:46 volumio volumio[862]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 08:18:46 volumio sudo[12521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-30 08:17 Nov 30 08:18:46 volumio sudo[12521]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"