-- Logs begin at Tue 2025-03-18 19:25:16 CST, end at Tue 2025-03-18 19:47:51 CST. -- Mar 18 19:46:19 volumio volumio-remote-updater[1737]: No test mode Mar 18 19:46:19 volumio volumio-remote-updater[1737]: No alpha test mode Mar 18 19:46:19 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 18 19:46:20 volumio volumio[5064]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 18 19:46:20 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 18 19:47:05 volumio volumio[5064]: info: Mar 18 19:47:05 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:05 volumio volumio[5064]: info: Mar 18 19:47:05 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:05 volumio volumio[5064]: info: Mar 18 19:47:05 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand status took 3 milliseconds Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand status took 2 milliseconds Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand status took 2 milliseconds Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::parseTrackInfo Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::parseTrackInfo Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::parseTrackInfo Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:05 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:05 volumio volumio[5064]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":88971,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"771 Kbps","isStreaming":false,"title":"身骑白马","artist":"徐佳莹","album":"LaLa首张创作专辑","uri":"http://aqqmusic.tc.qq.com/F000000sy6U01nQY9w.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=A8E1A0C44FF1E08D72FD8CB1571772951DCFDABA9493E934BAC903D4FF7510898E68E33F2016EED739D32BAD951B34711EFD254D1C2F2956__v21ea05dd4&uin=717741226&src=F0000031mx9F4YRRPR.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:05 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState stateService pause Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::stPlaybackTimer Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:05 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:05 volumio volumio[5064]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":88971,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"771 Kbps","isStreaming":false,"title":"身骑白马","artist":"徐佳莹","album":"LaLa首张创作专辑","uri":"http://aqqmusic.tc.qq.com/F000000sy6U01nQY9w.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=A8E1A0C44FF1E08D72FD8CB1571772951DCFDABA9493E934BAC903D4FF7510898E68E33F2016EED739D32BAD951B34711EFD254D1C2F2956__v21ea05dd4&uin=717741226&src=F0000031mx9F4YRRPR.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:05 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState stateService pause Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::stPlaybackTimer Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:05 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:05 volumio volumio[5064]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":88971,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"771 Kbps","isStreaming":false,"title":"身骑白马","artist":"徐佳莹","album":"LaLa首张创作专辑","uri":"http://aqqmusic.tc.qq.com/F000000sy6U01nQY9w.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=A8E1A0C44FF1E08D72FD8CB1571772951DCFDABA9493E934BAC903D4FF7510898E68E33F2016EED739D32BAD951B34711EFD254D1C2F2956__v21ea05dd4&uin=717741226&src=F0000031mx9F4YRRPR.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:05 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState stateService pause Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::stPlaybackTimer Mar 18 19:47:05 volumio volumio[5064]: info: ------------------------------ 36ms Mar 18 19:47:05 volumio volumio[5064]: info: ------------------------------ 34ms Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioGetQueue Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::getQueue Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getQueue Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioGetQueue Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::getQueue Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getQueue Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioGetQueue Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::getQueue Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getQueue Mar 18 19:47:05 volumio volumio[5064]: info: Clearing queue after UPNP request Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::ClearQueue Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::stop Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::stPlaybackTimer Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::updateTrackBlock Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrackBlock Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::serviceStop Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::stop Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand stop Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::clearPlayQueue Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::saveQueue Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushQueue Mar 18 19:47:05 volumio volumio[5064]: info: Mar 18 19:47:05 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:05 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:05 volumio volumio[5064]: info: Mar 18 19:47:05 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand stop took 24 milliseconds Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:05 volumio volumio[5064]: info: Mar 18 19:47:05 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:05 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:05 volumio volumio[5064]: info: Mar 18 19:47:05 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:05 volumio volumio[5064]: info: Mar 18 19:47:05 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:05 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:05 volumio volumio[5064]: info: Mar 18 19:47:05 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:05 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:05 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:05 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:05 volumio volumio[5064]: info: ------------------------------ 10ms Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand status took 10 milliseconds Mar 18 19:47:05 volumio volumio[5064]: info: ------------------------------ 9ms Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand status took 7 milliseconds Mar 18 19:47:05 volumio volumio[5064]: info: ------------------------------ 7ms Mar 18 19:47:05 volumio volumio[5064]: info: sendMpdCommand status took 6 milliseconds Mar 18 19:47:05 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: 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} Mar 18 19:47:05 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState stateService stop Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus stop Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: No code Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: 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} Mar 18 19:47:05 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState stateService stop Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus stop Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: No code Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: 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} Mar 18 19:47:05 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState stateService stop Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus stop Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: No code Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:05 volumio volumio[5064]: info: ------------------------------ 112ms Mar 18 19:47:05 volumio volumio[5064]: info: ------------------------------ 110ms Mar 18 19:47:05 volumio volumio[5064]: info: ------------------------------ 109ms Mar 18 19:47:05 volumio volumio[5064]: info: Starting UPNP Playback Mar 18 19:47:05 volumio volumio[5064]: info: Preparing playback through UPNP Mar 18 19:47:05 volumio volumio[5064]: info: CoreCommandRouter::volumioGetState Mar 18 19:47:05 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:05 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 18 19:47:06 volumio volumio[5064]: info: Clearing queue after UPNP request Mar 18 19:47:06 volumio volumio[5064]: info: CoreStateMachine::ClearQueue Mar 18 19:47:06 volumio volumio[5064]: info: CoreStateMachine::stop Mar 18 19:47:06 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 19:47:06 volumio volumio[5064]: info: CorePlayQueue::clearPlayQueue Mar 18 19:47:06 volumio volumio[5064]: info: CorePlayQueue::saveQueue Mar 18 19:47:06 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:06 volumio volumio[5064]: info: CoreCommandRouter::volumioPushQueue Mar 18 19:47:06 volumio volumio[5064]: info: Mar 18 19:47:06 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:06 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:06 volumio volumio[5064]: info: Mar 18 19:47:06 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:06 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:06 volumio volumio[5064]: info: Mar 18 19:47:06 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:06 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:06 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:06 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:06 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:06 volumio volumio[5064]: info: ------------------------------ 4ms Mar 18 19:47:06 volumio volumio[5064]: info: ------------------------------ 3ms Mar 18 19:47:06 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:06 volumio volumio[5064]: info: Starting UPNP Playback Mar 18 19:47:06 volumio volumio[5064]: info: Preparing playback through UPNP Mar 18 19:47:06 volumio volumio[5064]: info: CoreCommandRouter::volumioGetState Mar 18 19:47:06 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:06 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 18 19:47:07 volumio volumio[5064]: info: Clearing queue after UPNP request Mar 18 19:47:07 volumio volumio[5064]: info: CoreStateMachine::ClearQueue Mar 18 19:47:07 volumio volumio[5064]: info: CoreStateMachine::stop Mar 18 19:47:07 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 19:47:07 volumio volumio[5064]: info: CorePlayQueue::clearPlayQueue Mar 18 19:47:07 volumio volumio[5064]: info: CorePlayQueue::saveQueue Mar 18 19:47:07 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:07 volumio volumio[5064]: info: CoreCommandRouter::volumioPushQueue Mar 18 19:47:07 volumio volumio[5064]: info: Mar 18 19:47:07 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:07 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:07 volumio volumio[5064]: info: Mar 18 19:47:07 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:07 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:07 volumio volumio[5064]: info: Mar 18 19:47:07 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:07 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:07 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:07 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:07 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:07 volumio volumio[5064]: info: ------------------------------ 4ms Mar 18 19:47:07 volumio volumio[5064]: info: ------------------------------ 3ms Mar 18 19:47:07 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:07 volumio volumio[5064]: info: Starting UPNP Playback Mar 18 19:47:07 volumio volumio[5064]: info: Preparing playback through UPNP Mar 18 19:47:07 volumio volumio[5064]: info: CoreCommandRouter::volumioGetState Mar 18 19:47:07 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:07 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 18 19:47:08 volumio volumio[5064]: info: Clearing queue after UPNP request Mar 18 19:47:08 volumio volumio[5064]: info: CoreStateMachine::ClearQueue Mar 18 19:47:08 volumio volumio[5064]: info: CoreStateMachine::stop Mar 18 19:47:08 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 19:47:08 volumio volumio[5064]: info: CorePlayQueue::clearPlayQueue Mar 18 19:47:08 volumio volumio[5064]: info: CorePlayQueue::saveQueue Mar 18 19:47:08 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:08 volumio volumio[5064]: info: CoreCommandRouter::volumioPushQueue Mar 18 19:47:08 volumio volumio[5064]: info: Mar 18 19:47:08 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:08 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:08 volumio volumio[5064]: info: Mar 18 19:47:08 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:08 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:08 volumio volumio[5064]: info: Mar 18 19:47:08 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:08 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:08 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:08 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:08 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:08 volumio volumio[5064]: info: ------------------------------ 4ms Mar 18 19:47:08 volumio volumio[5064]: info: ------------------------------ 3ms Mar 18 19:47:08 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:08 volumio volumio[5064]: info: Starting UPNP Playback Mar 18 19:47:08 volumio volumio[5064]: info: Preparing playback through UPNP Mar 18 19:47:08 volumio volumio[5064]: info: CoreCommandRouter::volumioGetState Mar 18 19:47:08 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:08 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 18 19:47:09 volumio volumio[5064]: info: Clearing queue after UPNP request Mar 18 19:47:09 volumio volumio[5064]: info: CoreStateMachine::ClearQueue Mar 18 19:47:09 volumio volumio[5064]: info: CoreStateMachine::stop Mar 18 19:47:09 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 19:47:09 volumio volumio[5064]: info: CorePlayQueue::clearPlayQueue Mar 18 19:47:09 volumio volumio[5064]: info: CorePlayQueue::saveQueue Mar 18 19:47:09 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:09 volumio volumio[5064]: info: CoreCommandRouter::volumioPushQueue Mar 18 19:47:09 volumio volumio[5064]: info: Mar 18 19:47:09 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:09 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:09 volumio volumio[5064]: info: Mar 18 19:47:09 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:09 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:09 volumio volumio[5064]: info: Mar 18 19:47:09 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:09 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:09 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:09 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:09 volumio volumio[5064]: error: updateQueue error: null Mar 18 19:47:09 volumio volumio[5064]: info: ------------------------------ 4ms Mar 18 19:47:09 volumio volumio[5064]: info: ------------------------------ 3ms Mar 18 19:47:09 volumio volumio[5064]: info: ------------------------------ 3ms Mar 18 19:47:09 volumio volumio[5064]: info: Starting UPNP Playback Mar 18 19:47:09 volumio volumio[5064]: info: Preparing playback through UPNP Mar 18 19:47:09 volumio volumio[5064]: info: CoreCommandRouter::volumioGetState Mar 18 19:47:09 volumio volumio[5064]: info: CorePlayQueue::getTrack 0 Mar 18 19:47:09 volumio volumio[5064]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 3ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 3ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 1ms Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 1ms Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 1ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 3ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 1ms Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 3ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 2ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 1ms Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand status took 4 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand status took 7 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand status took 6 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand status took 4 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand status took 3 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand status took 2 milliseconds Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::parseTrackInfo Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:10 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:10 volumio volumio[5064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"泡沫","artist":null,"album":"Xposed","uri":"http://aqqmusic.tc.qq.com/F000000HjG8v1DTWRO.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=0434C7668E480C9356FBDF01E62EB0EF83E67A5AEC5567A6B601DD950B732D32C3C17006E4F3F467C547B077D3308A1569F1BAAA21EEBBC9__v21ea05dd4&uin=717741226&src=F000000ryYx71hFmdF.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:10 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState stateService play Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus stop Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:10 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:10 volumio volumio[5064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"泡沫","artist":null,"album":"Xposed","uri":"http://aqqmusic.tc.qq.com/F000000HjG8v1DTWRO.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=0434C7668E480C9356FBDF01E62EB0EF83E67A5AEC5567A6B601DD950B732D32C3C17006E4F3F467C547B077D3308A1569F1BAAA21EEBBC9__v21ea05dd4&uin=717741226&src=F000000ryYx71hFmdF.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:10 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState stateService play Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:10 volumio volumio[5064]: info: Received an update from plugin. extracting info from payload Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:10 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:10 volumio volumio[5064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"泡沫","artist":null,"album":"Xposed","uri":"http://aqqmusic.tc.qq.com/F000000HjG8v1DTWRO.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=0434C7668E480C9356FBDF01E62EB0EF83E67A5AEC5567A6B601DD950B732D32C3C17006E4F3F467C547B077D3308A1569F1BAAA21EEBBC9__v21ea05dd4&uin=717741226&src=F000000ryYx71hFmdF.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:10 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState stateService play Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:10 volumio volumio[5064]: info: Received an update from plugin. extracting info from payload Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 34ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 55ms Mar 18 19:47:10 volumio volumio[5064]: info: ------------------------------ 55ms Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:10 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:10 volumio volumio[5064]: info: Mar 18 19:47:10 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 42 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:10 volumio volumio[5064]: verbose: ControllerMpd::parseTrackInfo Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:10 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:10 volumio volumio[5064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"泡沫","artist":null,"album":"Xposed","uri":"http://aqqmusic.tc.qq.com/F000000HjG8v1DTWRO.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=0434C7668E480C9356FBDF01E62EB0EF83E67A5AEC5567A6B601DD950B732D32C3C17006E4F3F467C547B077D3308A1569F1BAAA21EEBBC9__v21ea05dd4&uin=717741226&src=F000000ryYx71hFmdF.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:10 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState stateService play Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:10 volumio volumio[5064]: info: Received an update from plugin. extracting info from payload Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:10 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:10 volumio volumio[5064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"泡沫","artist":null,"album":"Xposed","uri":"http://aqqmusic.tc.qq.com/F000000HjG8v1DTWRO.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=0434C7668E480C9356FBDF01E62EB0EF83E67A5AEC5567A6B601DD950B732D32C3C17006E4F3F467C547B077D3308A1569F1BAAA21EEBBC9__v21ea05dd4&uin=717741226&src=F000000ryYx71hFmdF.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:10 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState stateService play Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:10 volumio volumio[5064]: info: Received an update from plugin. extracting info from payload Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:10 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:10 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:10 volumio volumio[5064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"泡沫","artist":null,"album":"Xposed","uri":"http://aqqmusic.tc.qq.com/F000000HjG8v1DTWRO.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=0434C7668E480C9356FBDF01E62EB0EF83E67A5AEC5567A6B601DD950B732D32C3C17006E4F3F467C547B077D3308A1569F1BAAA21EEBBC9__v21ea05dd4&uin=717741226&src=F000000ryYx71hFmdF.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:10 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState stateService play Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:10 volumio volumio[5064]: info: Received an update from plugin. extracting info from payload Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:10 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:11 volumio volumio[5064]: info: ------------------------------ 117ms Mar 18 19:47:11 volumio volumio[5064]: info: ------------------------------ 112ms Mar 18 19:47:11 volumio volumio[5064]: info: Mar 18 19:47:11 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:11 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:11 volumio volumio[5064]: info: Mar 18 19:47:11 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:11 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:11 volumio volumio[5064]: info: Mar 18 19:47:11 volumio volumio[5064]: ---------------------------- MPD announces system playlist update Mar 18 19:47:11 volumio volumio[5064]: info: Ignoring MPD Status Update Mar 18 19:47:11 volumio volumio[5064]: info: Mar 18 19:47:11 volumio volumio[5064]: ---------------------------- MPD announces state update: player Mar 18 19:47:11 volumio volumio[5064]: info: ControllerMpd::getState Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand status Mar 18 19:47:11 volumio volumio[5064]: info: ------------------------------ 85ms Mar 18 19:47:11 volumio volumio[5064]: info: sendMpdCommand status took 83 milliseconds Mar 18 19:47:11 volumio volumio[5064]: info: ------------------------------ 7ms Mar 18 19:47:11 volumio volumio[5064]: info: sendMpdCommand status took 6 milliseconds Mar 18 19:47:11 volumio volumio[5064]: info: ------------------------------ 4ms Mar 18 19:47:11 volumio volumio[5064]: info: sendMpdCommand status took 3 milliseconds Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::parseState Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 19:47:11 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 18 19:47:11 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 18 19:47:11 volumio volumio[5064]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::parseTrackInfo Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::parseTrackInfo Mar 18 19:47:11 volumio volumio[5064]: verbose: ControllerMpd::parseTrackInfo Mar 18 19:47:11 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:11 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:11 volumio volumio[5064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"631 Kbps","isStreaming":false,"title":"泡沫","artist":"G.E.M. 邓紫棋","album":"Xposed","uri":"http://aqqmusic.tc.qq.com/F000000HjG8v1DTWRO.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=0434C7668E480C9356FBDF01E62EB0EF83E67A5AEC5567A6B601DD950B732D32C3C17006E4F3F467C547B077D3308A1569F1BAAA21EEBBC9__v21ea05dd4&uin=717741226&src=F000000ryYx71hFmdF.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:11 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::syncState stateService play Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:11 volumio volumio[5064]: info: Received an update from plugin. extracting info from payload Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:11 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:11 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:11 volumio volumio[5064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"561 Kbps","isStreaming":false,"title":"泡沫","artist":"G.E.M. 邓紫棋","album":"Xposed","uri":"http://aqqmusic.tc.qq.com/F000000HjG8v1DTWRO.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=0434C7668E480C9356FBDF01E62EB0EF83E67A5AEC5567A6B601DD950B732D32C3C17006E4F3F467C547B077D3308A1569F1BAAA21EEBBC9__v21ea05dd4&uin=717741226&src=F000000ryYx71hFmdF.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:11 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::syncState stateService play Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:11 volumio volumio[5064]: info: Received an update from plugin. extracting info from payload Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:11 volumio volumio[5064]: info: ControllerMpd::pushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::servicePushState Mar 18 19:47:11 volumio volumio[5064]: verbose: In UPNP mode Mar 18 19:47:11 volumio volumio[5064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"561 Kbps","isStreaming":false,"title":"泡沫","artist":"G.E.M. 邓紫棋","album":"Xposed","uri":"http://aqqmusic.tc.qq.com/F000000HjG8v1DTWRO.flac?guid=C93DE23341CD4C4E88A66FA240AC08F6&vkey=0434C7668E480C9356FBDF01E62EB0EF83E67A5AEC5567A6B601DD950B732D32C3C17006E4F3F467C547B077D3308A1569F1BAAA21EEBBC9__v21ea05dd4&uin=717741226&src=F000000ryYx71hFmdF.flac&redirect=1&fromtag=101114","trackType":""} Mar 18 19:47:11 volumio volumio[5064]: verbose: CURRENT POSITION 0 Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::syncState stateService play Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::syncState currentStatus play Mar 18 19:47:11 volumio volumio[5064]: info: Received an update from plugin. extracting info from payload Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::pushState Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioPushState Mar 18 19:47:11 volumio volumio[5064]: info: ------------------------------ 151ms Mar 18 19:47:11 volumio volumio[5064]: info: ------------------------------ 74ms Mar 18 19:47:11 volumio volumio[5064]: info: ------------------------------ 72ms Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioGetQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CorePlayQueue::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioGetQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CorePlayQueue::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioGetQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CorePlayQueue::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioGetQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CorePlayQueue::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioGetQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CorePlayQueue::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreCommandRouter::volumioGetQueue Mar 18 19:47:11 volumio volumio[5064]: info: CoreStateMachine::getQueue Mar 18 19:47:11 volumio volumio[5064]: info: CorePlayQueue::getQueue Mar 18 19:47:50 volumio volumio[5064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 19:47:50 volumio volumio[5064]: Error: connect ETIMEDOUT 31.13.67.19:443 Mar 18 19:47:50 volumio volumio[5064]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 18 19:47:50 volumio volumio[5064]: errno: -110, Mar 18 19:47:50 volumio volumio[5064]: code: 'ETIMEDOUT', Mar 18 19:47:50 volumio volumio[5064]: syscall: 'connect', Mar 18 19:47:50 volumio volumio[5064]: address: '31.13.67.19', Mar 18 19:47:50 volumio volumio[5064]: port: 443 Mar 18 19:47:50 volumio volumio[5064]: } Mar 18 19:47:50 volumio volumio[5064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 19:47:51 volumio sudo[6403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 19:46 Mar 18 19:47:51 volumio sudo[6403]: 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="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"