-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-12-11 22:59:21 CST. -- Dec 11 22:58:24 volumio volumio[3999]: info: Dec 11 22:58:24 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:24 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:24 volumio volumio[3999]: info: Dec 11 22:58:24 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:24 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:24 volumio volumio[3999]: info: Dec 11 22:58:24 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:24 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:24 volumio volumio[3999]: info: sendMpdCommand status took 8 milliseconds Dec 11 22:58:24 volumio volumio[3999]: info: sendMpdCommand status took 5 milliseconds Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:24 volumio volumio[3999]: info: sendMpdCommand status took 5 milliseconds Dec 11 22:58:24 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:24 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:24 volumio volumio[3999]: info: CorePlayQueue::getTrack 0 Dec 11 22:58:24 volumio volumio[3999]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"明月千里寄相思","artist":"付春霞","album":"付春霞中音作品集","uri":"http://aqqmusic.tc.qq.com/M800001bSHT221JZrL.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=2194F23A6EC19A2D2454ADA61064B60F63355863AA6B8E072256493D93C92E3C5FC0EF9D9B2436959129F79BE604A0D1F1A64DA71A6B35E3__v2b94c2e1&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:24 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::syncState stateService stop Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:58:24 volumio volumio[3999]: info: ------------------------------ 57ms Dec 11 22:58:24 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 48 milliseconds Dec 11 22:58:24 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 46 milliseconds Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:24 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:24 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:24 volumio volumio[3999]: info: CorePlayQueue::getTrack 0 Dec 11 22:58:24 volumio volumio[3999]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"明月千里寄相思","artist":"付春霞","album":"付春霞中音作品集","uri":"http://aqqmusic.tc.qq.com/M800001bSHT221JZrL.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=2194F23A6EC19A2D2454ADA61064B60F63355863AA6B8E072256493D93C92E3C5FC0EF9D9B2436959129F79BE604A0D1F1A64DA71A6B35E3__v2b94c2e1&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:24 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::syncState stateService stop Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus stop Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:24 volumio volumio[3999]: info: No code Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:24 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:24 volumio volumio[3999]: info: CorePlayQueue::getTrack 0 Dec 11 22:58:24 volumio volumio[3999]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"明月千里寄相思","artist":"付春霞","album":"付春霞中音作品集","uri":"http://aqqmusic.tc.qq.com/M800001bSHT221JZrL.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=2194F23A6EC19A2D2454ADA61064B60F63355863AA6B8E072256493D93C92E3C5FC0EF9D9B2436959129F79BE604A0D1F1A64DA71A6B35E3__v2b94c2e1&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:24 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::syncState stateService stop Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus stop Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:24 volumio volumio[3999]: info: No code Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:24 volumio volumio[3999]: info: ------------------------------ 191ms Dec 11 22:58:24 volumio volumio[3999]: info: ------------------------------ 191ms Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:24 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:24 volumio volumio[3999]: info: Clearing queue after UPNP request Dec 11 22:58:24 volumio volumio[3999]: info: touch_display: Setting screensaver timeout to 9007199254740991 seconds. Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::ClearQueue Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::stop Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:58:24 volumio volumio[3999]: info: CorePlayQueue::clearPlayQueue Dec 11 22:58:24 volumio volumio[3999]: info: CorePlayQueue::saveQueue Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioPushQueue Dec 11 22:58:24 volumio volumio[3999]: info: Dec 11 22:58:24 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:24 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:24 volumio volumio[3999]: info: Dec 11 22:58:24 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:24 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:24 volumio volumio[3999]: info: Dec 11 22:58:24 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:24 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:24 volumio volumio[3999]: error: updateQueue error: null Dec 11 22:58:24 volumio volumio[3999]: error: updateQueue error: null Dec 11 22:58:24 volumio volumio[3999]: error: updateQueue error: null Dec 11 22:58:24 volumio volumio[3999]: info: ------------------------------ 9ms Dec 11 22:58:24 volumio volumio[3999]: info: ------------------------------ 7ms Dec 11 22:58:24 volumio volumio[3999]: info: ------------------------------ 7ms Dec 11 22:58:24 volumio volumio[3999]: info: Starting UPNP Playback Dec 11 22:58:24 volumio volumio[3999]: info: Preparing playback through UPNP Dec 11 22:58:24 volumio volumio[3999]: info: CoreCommandRouter::volumioGetState Dec 11 22:58:24 volumio volumio[3999]: info: CorePlayQueue::getTrack 0 Dec 11 22:58:24 volumio volumio[3999]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 7ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 5ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 6ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 2ms Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 5ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 2ms Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: Dec 11 22:58:25 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:25 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 6ms Dec 11 22:58:25 volumio volumio[3999]: info: ------------------------------ 5ms Dec 11 22:58:26 volumio volumio[3999]: info: peppy_screensaver: Start PeppyMeter Dec 11 22:58:30 volumio volumio[3999]: Upnp client error: Error: This socket has been ended by the other party Dec 11 22:58:30 volumio volumio[3999]: info: Dec 11 22:58:30 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:30 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:30 volumio volumio[3999]: info: Dec 11 22:58:30 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:30 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:30 volumio volumio[3999]: info: Dec 11 22:58:30 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:30 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:30 volumio volumio[3999]: Upnp client error: Error: This socket has been ended by the other party Dec 11 22:58:30 volumio volumio[3999]: info: sendMpdCommand status took 4 milliseconds Dec 11 22:58:30 volumio volumio[3999]: info: sendMpdCommand status took 3 milliseconds Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:30 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 11 22:58:30 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 11 22:58:30 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:30 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:30 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:30 volumio volumio[3999]: info: CorePlayQueue::getTrack 0 Dec 11 22:58:30 volumio volumio[3999]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"长亭外","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003NALOE0FiVoq.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=B3BBCF975925AC2A5BE325787C237895798D9DC1106B6321D5A7C15BF1F7A75B6F4EADAB41352A78BE8EA9ACD2009DFD451D5CC115A38BF7__v2b9ab9d8&uin=1152921504869461316&src=M800001xBu6d2HEY2t.mp3&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:30 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::syncState stateService stop Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus stop Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:30 volumio volumio[3999]: info: No code Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:30 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:30 volumio volumio[3999]: info: CorePlayQueue::getTrack 0 Dec 11 22:58:30 volumio volumio[3999]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"长亭外","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003NALOE0FiVoq.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=B3BBCF975925AC2A5BE325787C237895798D9DC1106B6321D5A7C15BF1F7A75B6F4EADAB41352A78BE8EA9ACD2009DFD451D5CC115A38BF7__v2b9ab9d8&uin=1152921504869461316&src=M800001xBu6d2HEY2t.mp3&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:30 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::syncState stateService stop Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus stop Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:30 volumio volumio[3999]: info: No code Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:30 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:30 volumio volumio[3999]: info: CorePlayQueue::getTrack 0 Dec 11 22:58:30 volumio volumio[3999]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"长亭外","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003NALOE0FiVoq.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=B3BBCF975925AC2A5BE325787C237895798D9DC1106B6321D5A7C15BF1F7A75B6F4EADAB41352A78BE8EA9ACD2009DFD451D5CC115A38BF7__v2b9ab9d8&uin=1152921504869461316&src=M800001xBu6d2HEY2t.mp3&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:30 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::syncState stateService stop Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus stop Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:30 volumio volumio[3999]: info: No code Dec 11 22:58:30 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:30 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:30 volumio volumio[3999]: info: ------------------------------ 151ms Dec 11 22:58:30 volumio volumio[3999]: info: ------------------------------ 149ms Dec 11 22:58:30 volumio volumio[3999]: info: ------------------------------ 149ms Dec 11 22:58:30 volumio volumio[3999]: info: Clearing queue after UPNP request Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::ClearQueue Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::stop Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:58:31 volumio volumio[3999]: info: CorePlayQueue::clearPlayQueue Dec 11 22:58:31 volumio volumio[3999]: info: CorePlayQueue::saveQueue Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::volumioPushQueue Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: error: updateQueue error: null Dec 11 22:58:31 volumio volumio[3999]: error: updateQueue error: null Dec 11 22:58:31 volumio volumio[3999]: error: updateQueue error: null Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 6ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 5ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:31 volumio volumio[3999]: info: Starting UPNP Playback Dec 11 22:58:31 volumio volumio[3999]: info: Preparing playback through UPNP Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::volumioGetState Dec 11 22:58:31 volumio volumio[3999]: info: CorePlayQueue::getTrack 0 Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 2ms Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 2ms Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 2ms Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 5ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 2ms Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 4ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 3ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 2ms Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:31 volumio volumio[3999]: info: sendMpdCommand status took 13 milliseconds Dec 11 22:58:31 volumio volumio[3999]: info: sendMpdCommand status took 11 milliseconds Dec 11 22:58:31 volumio volumio[3999]: info: sendMpdCommand status took 9 milliseconds Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:31 volumio volumio[3999]: info: sendMpdCommand status took 2 milliseconds Dec 11 22:58:31 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 11 22:58:31 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 11 22:58:31 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:31 volumio volumio[3999]: verbose: In UPNP mode Dec 11 22:58:31 volumio volumio[3999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"雁南飞","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003pgvlW0n7Law.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=7065B471925AC021EE9869E20E15D571B03FB6E0A2148CD3FF3FF1A19E97100613CC237E10B44F4E1D7BDBD1BEFCD64C4E79F0B1826AB892__v2ba83c09&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:31 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::syncState stateService play Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus stop Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:31 volumio volumio[3999]: verbose: In UPNP mode Dec 11 22:58:31 volumio volumio[3999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"雁南飞","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003pgvlW0n7Law.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=7065B471925AC021EE9869E20E15D571B03FB6E0A2148CD3FF3FF1A19E97100613CC237E10B44F4E1D7BDBD1BEFCD64C4E79F0B1826AB892__v2ba83c09&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:31 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::syncState stateService play Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:58:31 volumio volumio[3999]: info: Received an update from plugin. extracting info from payload Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:31 volumio volumio[3999]: verbose: In UPNP mode Dec 11 22:58:31 volumio volumio[3999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"雁南飞","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003pgvlW0n7Law.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=7065B471925AC021EE9869E20E15D571B03FB6E0A2148CD3FF3FF1A19E97100613CC237E10B44F4E1D7BDBD1BEFCD64C4E79F0B1826AB892__v2ba83c09&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:31 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::syncState stateService play Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:58:31 volumio volumio[3999]: info: Received an update from plugin. extracting info from payload Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 44ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 105ms Dec 11 22:58:31 volumio volumio[3999]: info: ------------------------------ 104ms Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:31 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:31 volumio volumio[3999]: info: Dec 11 22:58:31 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:31 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 99 milliseconds Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:31 volumio volumio[3999]: info: sendMpdCommand status took 6 milliseconds Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:31 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:31 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:31 volumio volumio[3999]: verbose: In UPNP mode Dec 11 22:58:31 volumio volumio[3999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"雁南飞","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003pgvlW0n7Law.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=7065B471925AC021EE9869E20E15D571B03FB6E0A2148CD3FF3FF1A19E97100613CC237E10B44F4E1D7BDBD1BEFCD64C4E79F0B1826AB892__v2ba83c09&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:31 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::syncState stateService play Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:58:31 volumio volumio[3999]: info: Received an update from plugin. extracting info from payload Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 11 22:58:31 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:31 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: ------------------------------ 154ms Dec 11 22:58:32 volumio volumio[3999]: info: Dec 11 22:58:32 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:32 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:32 volumio volumio[3999]: info: Dec 11 22:58:32 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:32 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:32 volumio volumio[3999]: info: Dec 11 22:58:32 volumio volumio[3999]: ---------------------------- MPD announces system playlist update Dec 11 22:58:32 volumio volumio[3999]: info: Ignoring MPD Status Update Dec 11 22:58:32 volumio volumio[3999]: info: Dec 11 22:58:32 volumio volumio[3999]: ---------------------------- MPD announces state update: player Dec 11 22:58:32 volumio volumio[3999]: info: ControllerMpd::getState Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: sendMpdCommand status took 107 milliseconds Dec 11 22:58:32 volumio volumio[3999]: info: ------------------------------ 105ms Dec 11 22:58:32 volumio volumio[3999]: info: sendMpdCommand status took 105 milliseconds Dec 11 22:58:32 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 103 milliseconds Dec 11 22:58:32 volumio volumio[3999]: info: ------------------------------ 55ms Dec 11 22:58:32 volumio volumio[3999]: info: sendMpdCommand status took 54 milliseconds Dec 11 22:58:32 volumio volumio[3999]: info: ------------------------------ 54ms Dec 11 22:58:32 volumio volumio[3999]: info: sendMpdCommand status took 54 milliseconds Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::parseState Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:58:32 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:32 volumio volumio[3999]: verbose: In UPNP mode Dec 11 22:58:32 volumio volumio[3999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"12 雁南飞","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003pgvlW0n7Law.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=7065B471925AC021EE9869E20E15D571B03FB6E0A2148CD3FF3FF1A19E97100613CC237E10B44F4E1D7BDBD1BEFCD64C4E79F0B1826AB892__v2ba83c09&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:32 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState stateService play Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:58:32 volumio volumio[3999]: info: Received an update from plugin. extracting info from payload Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: ------------------------------ 180ms Dec 11 22:58:32 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 66 milliseconds Dec 11 22:58:32 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 66 milliseconds Dec 11 22:58:32 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 65 milliseconds Dec 11 22:58:32 volumio volumio[3999]: info: sendMpdCommand playlistinfo took 69 milliseconds Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:32 volumio volumio[3999]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:58:32 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:32 volumio volumio[3999]: verbose: In UPNP mode Dec 11 22:58:32 volumio volumio[3999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"12 雁南飞","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003pgvlW0n7Law.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=7065B471925AC021EE9869E20E15D571B03FB6E0A2148CD3FF3FF1A19E97100613CC237E10B44F4E1D7BDBD1BEFCD64C4E79F0B1826AB892__v2ba83c09&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:32 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState stateService play Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:58:32 volumio volumio[3999]: info: Received an update from plugin. extracting info from payload Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:32 volumio volumio[3999]: verbose: In UPNP mode Dec 11 22:58:32 volumio volumio[3999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"12 雁南飞","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003pgvlW0n7Law.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=7065B471925AC021EE9869E20E15D571B03FB6E0A2148CD3FF3FF1A19E97100613CC237E10B44F4E1D7BDBD1BEFCD64C4E79F0B1826AB892__v2ba83c09&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:32 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState stateService play Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:58:32 volumio volumio[3999]: info: Received an update from plugin. extracting info from payload Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:32 volumio volumio[3999]: verbose: In UPNP mode Dec 11 22:58:32 volumio volumio[3999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"12 雁南飞","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003pgvlW0n7Law.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=7065B471925AC021EE9869E20E15D571B03FB6E0A2148CD3FF3FF1A19E97100613CC237E10B44F4E1D7BDBD1BEFCD64C4E79F0B1826AB892__v2ba83c09&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:32 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState stateService play Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:58:32 volumio volumio[3999]: info: Received an update from plugin. extracting info from payload Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: ControllerMpd::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::servicePushState Dec 11 22:58:32 volumio volumio[3999]: verbose: In UPNP mode Dec 11 22:58:32 volumio volumio[3999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"12 雁南飞","artist":"钟丽燕","album":"露华浓","uri":"http://aqqmusic.tc.qq.com/M800003pgvlW0n7Law.mp3?guid=ffffffffc73fb5da0000019a973bf78f&vkey=7065B471925AC021EE9869E20E15D571B03FB6E0A2148CD3FF3FF1A19E97100613CC237E10B44F4E1D7BDBD1BEFCD64C4E79F0B1826AB892__v2ba83c09&uin=1152921504869461316&redirect=1&fromtag=111093","trackType":""} Dec 11 22:58:32 volumio volumio[3999]: verbose: CURRENT POSITION 0 Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState stateService play Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:58:32 volumio volumio[3999]: info: Received an update from plugin. extracting info from payload Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::pushState Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioPushState Dec 11 22:58:32 volumio volumio[3999]: info: ------------------------------ 378ms Dec 11 22:58:32 volumio volumio[3999]: info: ------------------------------ 376ms Dec 11 22:58:32 volumio volumio[3999]: info: ------------------------------ 325ms Dec 11 22:58:32 volumio volumio[3999]: info: ------------------------------ 324ms Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:58:32 volumio volumio[3999]: info: CoreStateMachine::getQueue Dec 11 22:58:32 volumio volumio[3999]: info: CorePlayQueue::getQueue Dec 11 22:58:41 volumio volumio[3999]: verbose: New Socket.io Connection to 192.168.5.6 from 192.168.5.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.5735.196 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::volumioGetState Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 22:58:41 volumio volumio[3999]: info: Received Get System Info Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 22:58:41 volumio volumio[3999]: info: Discovery: Getting this device information Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::volumioGetState Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 22:58:41 volumio volumio[3999]: info: CoreCommandRouter::volumioGetState Dec 11 22:58:41 volumio volumio[3999]: info: Listing playlists Dec 11 22:58:41 volumio volumio[3999]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%92%9F%E4%B8%BD%E7%87%95/%E9%9C%B2%E5%8D%8E%E6%B5%93/68d24fdd-85a4-4ca3-a624-d6ceab260ef5.png' Dec 11 22:58:42 volumio volumio[3999]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 22:58:43 volumio volumio[3999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Dec 11 22:58:47 volumio volumio[3999]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Dec 11 22:58:47 volumio volumio[3999]: info: CoreCommandRouter::volumioGetState Dec 11 22:58:47 volumio volumio[3999]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%92%9F%E4%B8%BD%E7%87%95/%E9%9C%B2%E5%8D%8E%E6%B5%93/68d24fdd-85a4-4ca3-a624-d6ceab260ef5.png' Dec 11 22:58:48 volumio volumio[3999]: info: CoreCommandRouter::volumioGetState Dec 11 22:59:21 volumio volumio[3999]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 22:59:21 volumio volumio[3999]: Error: connect ETIMEDOUT 108.160.165.141:443 Dec 11 22:59:21 volumio volumio[3999]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 11 22:59:21 volumio volumio[3999]: errno: -110, Dec 11 22:59:21 volumio volumio[3999]: code: 'ETIMEDOUT', Dec 11 22:59:21 volumio volumio[3999]: syscall: 'connect', Dec 11 22:59:21 volumio volumio[3999]: address: '108.160.165.141', Dec 11 22:59:21 volumio volumio[3999]: port: 443 Dec 11 22:59:21 volumio volumio[3999]: } Dec 11 22:59:21 volumio volumio[3999]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 22:59:21 volumio sudo[10198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-11 22:58 Dec 11 22:59:21 volumio sudo[10198]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"