-- Logs begin at Sat 2025-05-10 23:58:28 CST, end at Sun 2025-05-11 03:20:52 CST. -- May 11 03:19:12 volumio volumio[31106]: info: Clearing queue after UPNP request May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::ClearQueue May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::stop May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::stPlaybackTimer May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::updateTrackBlock May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrackBlock May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::serviceStop May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: ControllerMpd::stop May 11 03:19:13 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand stop May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::clearPlayQueue May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::saveQueue May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushQueue May 11 03:19:13 volumio volumio[31106]: info: sendMpdCommand stop took 23 milliseconds May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:19:13 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:19:13 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:19:13 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:19:13 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:19:13 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:19:13 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: sendMpdCommand status took 2 milliseconds May 11 03:19:13 volumio volumio[31106]: info: sendMpdCommand status took 1 milliseconds May 11 03:19:13 volumio volumio[31106]: info: sendMpdCommand status took 1 milliseconds May 11 03:19:13 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:19:13 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:19:13 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:19:13 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: 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} May 11 03:19:13 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::syncState stateService stop May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus stop May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: No code May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: 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} May 11 03:19:13 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::syncState stateService stop May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus stop May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: No code May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: 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} May 11 03:19:13 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::syncState stateService stop May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus stop May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: No code May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 27ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 26ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 26ms May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: error: updateQueue error: null May 11 03:19:13 volumio volumio[31106]: error: updateQueue error: null May 11 03:19:13 volumio volumio[31106]: error: updateQueue error: null May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 27ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: Starting UPNP Playback May 11 03:19:13 volumio volumio[31106]: info: Preparing playback through UPNP May 11 03:19:13 volumio volumio[31106]: info: CoreCommandRouter::volumioGetState May 11 03:19:13 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:19:13 volumio volumio[31106]: info: CoreStateMachine::setConsumeUpdateService mpd May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 2ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 2ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 2ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 4ms May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 2ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: May 11 03:19:13 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:19:13 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:19:13 volumio volumio[31106]: info: ------------------------------ 0ms May 11 03:19:14 volumio volumio[31106]: info: May 11 03:19:14 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:19:14 volumio volumio[31106]: info: May 11 03:19:14 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:19:14 volumio volumio[31106]: info: May 11 03:19:14 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:19:14 volumio volumio[31106]: info: May 11 03:19:14 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:19:14 volumio volumio[31106]: info: sendMpdCommand status took 7 milliseconds May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:19:14 volumio volumio[31106]: info: sendMpdCommand status took 7 milliseconds May 11 03:19:14 volumio volumio[31106]: info: sendMpdCommand status took 7 milliseconds May 11 03:19:14 volumio volumio[31106]: info: sendMpdCommand status took 1 milliseconds May 11 03:19:14 volumio volumio[31106]: info: sendMpdCommand playlistinfo took 1 milliseconds May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseTrackInfo May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:19:14 volumio volumio[31106]: verbose: In UPNP mode May 11 03:19:14 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我不想说","artist":"周虹 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/652d8f42d0f46c82670b2bb27db0934e/681fa6b0/resource/n3/8/35/1414987093.mp3","trackType":"mp3"} May 11 03:19:14 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus stop May 11 03:19:14 volumio volumio[31106]: info: ------------------------------ 10ms May 11 03:19:14 volumio volumio[31106]: info: May 11 03:19:14 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:19:14 volumio volumio[31106]: info: May 11 03:19:14 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:19:14 volumio volumio[31106]: info: sendMpdCommand playlistinfo took 4 milliseconds May 11 03:19:14 volumio volumio[31106]: info: sendMpdCommand status took 2 milliseconds May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseTrackInfo May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:19:14 volumio volumio[31106]: verbose: In UPNP mode May 11 03:19:14 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我不想说","artist":"周虹 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/652d8f42d0f46c82670b2bb27db0934e/681fa6b0/resource/n3/8/35/1414987093.mp3","trackType":"mp3"} May 11 03:19:14 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:19:14 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:19:14 volumio volumio[31106]: verbose: In UPNP mode May 11 03:19:14 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我不想说","artist":"周虹 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/652d8f42d0f46c82670b2bb27db0934e/681fa6b0/resource/n3/8/35/1414987093.mp3","trackType":"mp3"} May 11 03:19:14 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:19:14 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:19:14 volumio volumio[31106]: verbose: In UPNP mode May 11 03:19:14 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我不想说","artist":"周虹 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/652d8f42d0f46c82670b2bb27db0934e/681fa6b0/resource/n3/8/35/1414987093.mp3","trackType":"mp3"} May 11 03:19:14 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:19:14 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: ------------------------------ 47ms May 11 03:19:14 volumio volumio[31106]: info: ------------------------------ 47ms May 11 03:19:14 volumio volumio[31106]: info: ------------------------------ 43ms May 11 03:19:14 volumio volumio[31106]: info: sendMpdCommand playlistinfo took 36 milliseconds May 11 03:19:14 volumio volumio[31106]: info: sendMpdCommand playlistinfo took 37 milliseconds May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseTrackInfo May 11 03:19:14 volumio volumio[31106]: verbose: ControllerMpd::parseTrackInfo May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:19:14 volumio volumio[31106]: verbose: In UPNP mode May 11 03:19:14 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我不想说","artist":"周虹 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/652d8f42d0f46c82670b2bb27db0934e/681fa6b0/resource/n3/8/35/1414987093.mp3","trackType":"mp3"} May 11 03:19:14 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:19:14 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:19:14 volumio volumio[31106]: verbose: In UPNP mode May 11 03:19:14 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"我不想说","artist":"周虹 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/652d8f42d0f46c82670b2bb27db0934e/681fa6b0/resource/n3/8/35/1414987093.mp3","trackType":"mp3"} May 11 03:19:14 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:19:14 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:14 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:14 volumio volumio[31106]: info: ------------------------------ 71ms May 11 03:19:14 volumio volumio[31106]: info: ------------------------------ 71ms May 11 03:19:25 volumio volumio[1872]: 24 May 11 03:19:25 volumio volumio[1872]: % Total % Received % Xferd Average Speed Time Time Time Current May 11 03:19:25 volumio volumio[1872]: Dload Upload Total Spent Left Speed May 11 03:19:25 volumio volumio[31106]: info: May 11 03:19:25 volumio volumio[31106]: ---------------------------- Client requests Volume 24 May 11 03:19:25 volumio volumio[31106]: info: VolumeController::SetAlsaVolume24 May 11 03:19:25 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:19:25 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 03:19:25 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:19:25 volumio volumio[1872]: [158B blob data] May 11 03:19:25 volumio volumio[1872]: {"time":1746904765395,"response":"volume Success"}24 May 11 03:20:08 volumio volumio[31106]: info: Clearing queue after UPNP request May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::ClearQueue May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::stop May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::stPlaybackTimer May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::updateTrackBlock May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrackBlock May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::serviceStop May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: ControllerMpd::stop May 11 03:20:08 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand stop May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::clearPlayQueue May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::saveQueue May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushQueue May 11 03:20:08 volumio volumio[31106]: info: sendMpdCommand stop took 19 milliseconds May 11 03:20:08 volumio volumio[31106]: info: May 11 03:20:08 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:20:08 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:20:08 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:20:08 volumio volumio[31106]: info: May 11 03:20:08 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:20:08 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:20:08 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:20:08 volumio volumio[31106]: info: May 11 03:20:08 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:20:08 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:20:08 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:20:08 volumio volumio[31106]: info: May 11 03:20:08 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:08 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:08 volumio volumio[31106]: info: sendMpdCommand status took 1 milliseconds May 11 03:20:08 volumio volumio[31106]: info: sendMpdCommand status took 0 milliseconds May 11 03:20:08 volumio volumio[31106]: info: sendMpdCommand status took 1 milliseconds May 11 03:20:08 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:20:08 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:20:08 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:20:08 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: 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} May 11 03:20:08 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::syncState stateService stop May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus stop May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: No code May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: 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} May 11 03:20:08 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::syncState stateService stop May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus stop May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: No code May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: 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} May 11 03:20:08 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::syncState stateService stop May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus stop May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: No code May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:08 volumio volumio[31106]: info: ------------------------------ 25ms May 11 03:20:08 volumio volumio[31106]: info: ------------------------------ 25ms May 11 03:20:08 volumio volumio[31106]: info: ------------------------------ 24ms May 11 03:20:08 volumio volumio[31106]: info: May 11 03:20:08 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:08 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:08 volumio volumio[31106]: info: May 11 03:20:08 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:08 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:08 volumio volumio[31106]: error: updateQueue error: null May 11 03:20:08 volumio volumio[31106]: error: updateQueue error: null May 11 03:20:08 volumio volumio[31106]: error: updateQueue error: null May 11 03:20:08 volumio volumio[31106]: info: ------------------------------ 26ms May 11 03:20:08 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:08 volumio volumio[31106]: info: Starting UPNP Playback May 11 03:20:08 volumio volumio[31106]: info: Preparing playback through UPNP May 11 03:20:08 volumio volumio[31106]: info: CoreCommandRouter::volumioGetState May 11 03:20:08 volumio volumio[31106]: info: CorePlayQueue::getTrack 0 May 11 03:20:08 volumio volumio[31106]: info: CoreStateMachine::setConsumeUpdateService mpd May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 3ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 3ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 2ms May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 0ms May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 0ms May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces system playlist update May 11 03:20:09 volumio volumio[31106]: info: Ignoring MPD Status Update May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 1ms May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand status took 6 milliseconds May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand status took 6 milliseconds May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand status took 5 milliseconds May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand status took 1 milliseconds May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand playlistinfo took 1 milliseconds May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseTrackInfo May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:20:09 volumio volumio[31106]: verbose: In UPNP mode May 11 03:20:09 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"愿得一人心","artist":"谭艳 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/44621975032cb5a636893944f303723f/681fa6e8/resource/n2/38/75/3669572947.mp3","trackType":"mp3"} May 11 03:20:09 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus stop May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 9ms May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:20:09 volumio volumio[31106]: info: May 11 03:20:09 volumio volumio[31106]: ---------------------------- MPD announces state update: player May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::getState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand status May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand playlistinfo took 3 milliseconds May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand playlistinfo took 3 milliseconds May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand playlistinfo took 2 milliseconds May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand status took 1 milliseconds May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand status took 1 milliseconds May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseTrackInfo May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseTrackInfo May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseTrackInfo May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseState May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:20:09 volumio volumio[31106]: verbose: In UPNP mode May 11 03:20:09 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"愿得一人心","artist":"谭艳 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/44621975032cb5a636893944f303723f/681fa6e8/resource/n2/38/75/3669572947.mp3","trackType":"mp3"} May 11 03:20:09 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:20:09 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:20:09 volumio volumio[31106]: verbose: In UPNP mode May 11 03:20:09 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"愿得一人心","artist":"谭艳 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/44621975032cb5a636893944f303723f/681fa6e8/resource/n2/38/75/3669572947.mp3","trackType":"mp3"} May 11 03:20:09 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:20:09 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:20:09 volumio volumio[31106]: verbose: In UPNP mode May 11 03:20:09 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"愿得一人心","artist":"谭艳 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/44621975032cb5a636893944f303723f/681fa6e8/resource/n2/38/75/3669572947.mp3","trackType":"mp3"} May 11 03:20:09 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:20:09 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 83ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 83ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 79ms May 11 03:20:09 volumio volumio[31106]: info: sendMpdCommand playlistinfo took 75 milliseconds May 11 03:20:09 volumio volumio[31106]: verbose: ControllerMpd::parseTrackInfo May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:20:09 volumio volumio[31106]: verbose: In UPNP mode May 11 03:20:09 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"愿得一人心","artist":"谭艳 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/44621975032cb5a636893944f303723f/681fa6e8/resource/n2/38/75/3669572947.mp3","trackType":"mp3"} May 11 03:20:09 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:20:09 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: ControllerMpd::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::servicePushState May 11 03:20:09 volumio volumio[31106]: verbose: In UPNP mode May 11 03:20:09 volumio volumio[31106]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"愿得一人心","artist":"谭艳 (Performer)","album":null,"uri":"http://nf.sycdn.kuwo.cn/44621975032cb5a636893944f303723f/681fa6e8/resource/n2/38/75/3669572947.mp3","trackType":"mp3"} May 11 03:20:09 volumio volumio[31106]: verbose: CURRENT POSITION 0 May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState stateService play May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::syncState currentStatus play May 11 03:20:09 volumio volumio[31106]: info: Received an update from plugin. extracting info from payload May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: CoreStateMachine::pushState May 11 03:20:09 volumio volumio[31106]: info: CoreCommandRouter::volumioPushState May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 102ms May 11 03:20:09 volumio volumio[31106]: info: ------------------------------ 103ms May 11 03:20:52 volumio volumio[31106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 03:20:52 volumio volumio[31106]: Error: connect ETIMEDOUT 74.86.3.208:443 May 11 03:20:52 volumio volumio[31106]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 11 03:20:52 volumio volumio[31106]: errno: -110, May 11 03:20:52 volumio volumio[31106]: code: 'ETIMEDOUT', May 11 03:20:52 volumio volumio[31106]: syscall: 'connect', May 11 03:20:52 volumio volumio[31106]: address: '74.86.3.208', May 11 03:20:52 volumio volumio[31106]: port: 443 May 11 03:20:52 volumio volumio[31106]: } May 11 03:20:52 volumio volumio[31106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 03:20:52 volumio sudo[6291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 03:19 May 11 03:20:52 volumio sudo[6291]: 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="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"