-- Logs begin at Tue 2025-11-04 08:56:04 CST, end at Tue 2025-11-04 10:00:07 CST. -- Nov 04 09:59:29 volumio volumio[1179]: info: Nov 04 09:59:29 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:29 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:29 volumio volumio[1179]: info: Nov 04 09:59:29 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:29 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:29 volumio volumio[1179]: info: Nov 04 09:59:29 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:29 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:29 volumio volumio[1179]: info: sendMpdCommand status took 2 milliseconds Nov 04 09:59:29 volumio volumio[1179]: info: sendMpdCommand status took 1 milliseconds Nov 04 09:59:29 volumio volumio[1179]: info: sendMpdCommand status took 1 milliseconds Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:29 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 04 09:59:29 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 04 09:59:29 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:29 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:29 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:29 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 04 09:59:29 volumio volumio[1179]: 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":"Miss Ida B","artist":"Buddy Guy","album":"Buddy's Baddest: The Best Of Buddy Guy","uri":"http://lw.sycdn.kuwo.cn/9d49fda3864f5acf593899ccc6fbd8b6/69095d9e/resource/30106/trackmedia/M500001wUBrG3hnmwL.mp3","trackType":"mp3"} Nov 04 09:59:29 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::syncState stateService stop Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::stPlaybackTimer Nov 04 09:59:29 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:29 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 04 09:59:29 volumio volumio[1179]: 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":"Miss Ida B","artist":"Buddy Guy","album":"Buddy's Baddest: The Best Of Buddy Guy","uri":"http://lw.sycdn.kuwo.cn/9d49fda3864f5acf593899ccc6fbd8b6/69095d9e/resource/30106/trackmedia/M500001wUBrG3hnmwL.mp3","trackType":"mp3"} Nov 04 09:59:29 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::syncState stateService stop Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus stop Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:29 volumio volumio[1179]: info: No code Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:29 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:29 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 04 09:59:29 volumio volumio[1179]: 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":"Miss Ida B","artist":"Buddy Guy","album":"Buddy's Baddest: The Best Of Buddy Guy","uri":"http://lw.sycdn.kuwo.cn/9d49fda3864f5acf593899ccc6fbd8b6/69095d9e/resource/30106/trackmedia/M500001wUBrG3hnmwL.mp3","trackType":"mp3"} Nov 04 09:59:29 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::syncState stateService stop Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus stop Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:29 volumio volumio[1179]: info: No code Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:29 volumio volumio[1179]: info: ------------------------------ 49ms Nov 04 09:59:29 volumio volumio[1179]: info: ------------------------------ 49ms Nov 04 09:59:29 volumio volumio[1179]: info: ------------------------------ 48ms Nov 04 09:59:29 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:29 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:29 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:30 volumio volumio[1179]: info: Clearing queue after UPNP request Nov 04 09:59:30 volumio volumio[1179]: info: CoreStateMachine::ClearQueue Nov 04 09:59:30 volumio volumio[1179]: info: CoreStateMachine::stop Nov 04 09:59:30 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:59:30 volumio volumio[1179]: info: CorePlayQueue::clearPlayQueue Nov 04 09:59:30 volumio volumio[1179]: info: CorePlayQueue::saveQueue Nov 04 09:59:30 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:30 volumio volumio[1179]: info: CoreCommandRouter::volumioPushQueue Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: error: updateQueue error: null Nov 04 09:59:30 volumio volumio[1179]: error: updateQueue error: null Nov 04 09:59:30 volumio volumio[1179]: error: updateQueue error: null Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 3ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:30 volumio volumio[1179]: info: Starting UPNP Playback Nov 04 09:59:30 volumio volumio[1179]: info: Preparing playback through UPNP Nov 04 09:59:30 volumio volumio[1179]: info: CoreCommandRouter::volumioGetState Nov 04 09:59:30 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 04 09:59:30 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 5ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 4ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 4ms Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 5ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 3ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:30 volumio volumio[1179]: info: Nov 04 09:59:30 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:30 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:30 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand status took 2 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand status took 2 milliseconds Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand status took 3 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand status took 3 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:31 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:31 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La vie en rose","artist":"手嶌葵 (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:31 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus stop Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:31 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:31 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La vie en rose","artist":"手嶌葵 (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:31 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:31 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 16ms Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 21ms Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:31 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:31 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand status took 23 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 22 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 21 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand status took 4 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 3ms Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:31 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:31 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La vie en rose","artist":"手嶌葵 (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:31 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:31 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:31 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:31 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La vie en rose","artist":"手嶌葵 (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:31 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:31 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 54ms Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:31 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:31 volumio volumio[1179]: info: Nov 04 09:59:31 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand status took 44 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 45ms Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand status took 44 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 43 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 42 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 15ms Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand status took 9 milliseconds Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:31 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:31 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La vie en rose","artist":"手嶌葵","album":"La Vie En Rose～I Love Cinemas～","uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:31 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:31 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:31 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:31 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":637,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"La vie en rose","artist":"手嶌葵","album":"La Vie En Rose～I Love Cinemas～","uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:31 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:31 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 98ms Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 78ms Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 32 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 32 milliseconds Nov 04 09:59:31 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:31 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:31 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:31 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":637,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"La vie en rose","artist":"手嶌葵","album":"La Vie En Rose～I Love Cinemas～","uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:31 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:31 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:31 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:31 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":637,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"La vie en rose","artist":"手嶌葵","album":"La Vie En Rose～I Love Cinemas～","uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:31 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:31 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:31 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:31 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":671,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"La vie en rose","artist":"手嶌葵","album":"La Vie En Rose～I Love Cinemas～","uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:31 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:31 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 124ms Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 124ms Nov 04 09:59:31 volumio volumio[1179]: info: ------------------------------ 95ms Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:31 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:31 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:55 volumio volumio[1179]: info: Nov 04 09:59:55 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:55 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:55 volumio volumio[1179]: info: Nov 04 09:59:55 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:55 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:55 volumio volumio[1179]: info: Nov 04 09:59:55 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:55 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:55 volumio volumio[1179]: info: sendMpdCommand status took 3 milliseconds Nov 04 09:59:55 volumio volumio[1179]: info: sendMpdCommand status took 2 milliseconds Nov 04 09:59:55 volumio volumio[1179]: info: sendMpdCommand status took 1 milliseconds Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:55 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 04 09:59:55 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 04 09:59:55 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:55 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:55 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:55 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 04 09:59:55 volumio volumio[1179]: 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":"La vie en rose","artist":"手嶌葵","album":"La Vie En Rose～I Love Cinemas～","uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:55 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::syncState stateService stop Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::stPlaybackTimer Nov 04 09:59:55 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:55 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 04 09:59:55 volumio volumio[1179]: 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":"La vie en rose","artist":"手嶌葵","album":"La Vie En Rose～I Love Cinemas～","uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:55 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::syncState stateService stop Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus stop Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:55 volumio volumio[1179]: info: No code Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:55 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:55 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 04 09:59:55 volumio volumio[1179]: 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":"La vie en rose","artist":"手嶌葵","album":"La Vie En Rose～I Love Cinemas～","uri":"http://lw.sycdn.kuwo.cn/76f33b29bc3976192d1c39c0e53dc0ba/69095e02/resource/30106/trackmedia/M500002uJW3u3voJwV.mp3","trackType":"mp3"} Nov 04 09:59:55 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::syncState stateService stop Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus stop Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:55 volumio volumio[1179]: info: No code Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:55 volumio volumio[1179]: info: ------------------------------ 51ms Nov 04 09:59:55 volumio volumio[1179]: info: ------------------------------ 51ms Nov 04 09:59:55 volumio volumio[1179]: info: ------------------------------ 51ms Nov 04 09:59:55 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:55 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:55 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:56 volumio volumio[1179]: info: Clearing queue after UPNP request Nov 04 09:59:56 volumio volumio[1179]: info: CoreStateMachine::ClearQueue Nov 04 09:59:56 volumio volumio[1179]: info: CoreStateMachine::stop Nov 04 09:59:56 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:59:56 volumio volumio[1179]: info: CorePlayQueue::clearPlayQueue Nov 04 09:59:56 volumio volumio[1179]: info: CorePlayQueue::saveQueue Nov 04 09:59:56 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:56 volumio volumio[1179]: info: CoreCommandRouter::volumioPushQueue Nov 04 09:59:56 volumio volumio[1179]: info: Nov 04 09:59:56 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:56 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:56 volumio volumio[1179]: info: Nov 04 09:59:56 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:56 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:56 volumio volumio[1179]: info: Nov 04 09:59:56 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:56 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:56 volumio volumio[1179]: error: updateQueue error: null Nov 04 09:59:56 volumio volumio[1179]: error: updateQueue error: null Nov 04 09:59:56 volumio volumio[1179]: error: updateQueue error: null Nov 04 09:59:56 volumio volumio[1179]: info: ------------------------------ 3ms Nov 04 09:59:56 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:56 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:56 volumio volumio[1179]: info: Starting UPNP Playback Nov 04 09:59:56 volumio volumio[1179]: info: Preparing playback through UPNP Nov 04 09:59:56 volumio volumio[1179]: info: CoreCommandRouter::volumioGetState Nov 04 09:59:56 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 04 09:59:56 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 3ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 2ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 1ms Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand status took 2 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand status took 2 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand status took 2 milliseconds Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand status took 4 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand status took 2 milliseconds Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:57 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:57 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spooky","artist":"Dusty Springfield (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/c3eec29091e1416a09016e1f7fd2107e/69095e1c/resource/30106/trackmedia/M50000476fRl43xiiX.mp3","trackType":"mp3"} Nov 04 09:59:57 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus stop Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:57 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:57 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spooky","artist":"Dusty Springfield (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/c3eec29091e1416a09016e1f7fd2107e/69095e1c/resource/30106/trackmedia/M50000476fRl43xiiX.mp3","trackType":"mp3"} Nov 04 09:59:57 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:57 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:57 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:57 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spooky","artist":"Dusty Springfield (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/c3eec29091e1416a09016e1f7fd2107e/69095e1c/resource/30106/trackmedia/M50000476fRl43xiiX.mp3","trackType":"mp3"} Nov 04 09:59:57 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:57 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 22ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 35ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 35ms Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand status took 33 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 30 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:57 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:57 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spooky","artist":"Dusty Springfield (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/c3eec29091e1416a09016e1f7fd2107e/69095e1c/resource/30106/trackmedia/M50000476fRl43xiiX.mp3","trackType":"mp3"} Nov 04 09:59:57 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:57 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:57 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:57 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spooky","artist":"Dusty Springfield","album":"Lock, Stock and Two Smoking Barrels","uri":"http://lw.sycdn.kuwo.cn/c3eec29091e1416a09016e1f7fd2107e/69095e1c/resource/30106/trackmedia/M50000476fRl43xiiX.mp3","trackType":"mp3"} Nov 04 09:59:57 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:57 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 72ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 71ms Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 04 09:59:57 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 04 09:59:57 volumio volumio[1179]: info: Nov 04 09:59:57 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::getState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 50ms Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand status took 48 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 48 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 12ms Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand status took 12 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 11ms Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand status took 10 milliseconds Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:57 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:57 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spooky","artist":"Dusty Springfield","album":"Lock, Stock and Two Smoking Barrels","uri":"http://lw.sycdn.kuwo.cn/c3eec29091e1416a09016e1f7fd2107e/69095e1c/resource/30106/trackmedia/M50000476fRl43xiiX.mp3","trackType":"mp3"} Nov 04 09:59:57 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:57 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 106ms Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 25 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 04 09:59:57 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 24 milliseconds Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:57 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:57 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:57 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":637,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Spooky","artist":"Dusty Springfield","album":"Lock, Stock and Two Smoking Barrels","uri":"http://lw.sycdn.kuwo.cn/c3eec29091e1416a09016e1f7fd2107e/69095e1c/resource/30106/trackmedia/M50000476fRl43xiiX.mp3","trackType":"mp3"} Nov 04 09:59:57 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:57 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:57 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:57 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":671,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Spooky","artist":"Dusty Springfield","album":"Lock, Stock and Two Smoking Barrels","uri":"http://lw.sycdn.kuwo.cn/c3eec29091e1416a09016e1f7fd2107e/69095e1c/resource/30106/trackmedia/M50000476fRl43xiiX.mp3","trackType":"mp3"} Nov 04 09:59:57 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:57 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: ControllerMpd::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::servicePushState Nov 04 09:59:57 volumio volumio[1179]: verbose: In UPNP mode Nov 04 09:59:57 volumio volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":671,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Spooky","artist":"Dusty Springfield","album":"Lock, Stock and Two Smoking Barrels","uri":"http://lw.sycdn.kuwo.cn/c3eec29091e1416a09016e1f7fd2107e/69095e1c/resource/30106/trackmedia/M50000476fRl43xiiX.mp3","trackType":"mp3"} Nov 04 09:59:57 volumio volumio[1179]: verbose: CURRENT POSITION 0 Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState stateService play Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:59:57 volumio volumio[1179]: info: Received an update from plugin. extracting info from payload Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 139ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 101ms Nov 04 09:59:57 volumio volumio[1179]: info: ------------------------------ 101ms Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreCommandRouter::volumioGetQueue Nov 04 09:59:57 volumio volumio[1179]: info: CoreStateMachine::getQueue Nov 04 09:59:57 volumio volumio[1179]: info: CorePlayQueue::getQueue Nov 04 10:00:06 volumio volumio[1179]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 10:00:06 volumio volumio[1179]: Error: connect ETIMEDOUT 104.244.46.244:443 Nov 04 10:00:06 volumio volumio[1179]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 04 10:00:06 volumio volumio[1179]: errno: -110, Nov 04 10:00:06 volumio volumio[1179]: code: 'ETIMEDOUT', Nov 04 10:00:06 volumio volumio[1179]: syscall: 'connect', Nov 04 10:00:06 volumio volumio[1179]: address: '104.244.46.244', Nov 04 10:00:06 volumio volumio[1179]: port: 443 Nov 04 10:00:06 volumio volumio[1179]: } Nov 04 10:00:06 volumio volumio[1179]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 10:00:07 volumio sudo[19481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 09:59 Nov 04 10:00:07 volumio sudo[19481]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:21:23 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a9588e47e4b05f1a05bf70b4acc7555c"