-- Logs begin at Thu 2024-04-11 02:14:56 UTC, end at Thu 2024-04-11 03:24:42 UTC. -- Apr 11 03:23:25 volumio volumio[12660]: info: Apr 11 03:23:25 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:23:25 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:25 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:25 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:23:25 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:25 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:25 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 03:23:25 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:25 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:25 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:25 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:25 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:25 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:25 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:23:25 volumio volumio[12660]: 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://182.242.50.152/amobile.music.tc.qq.com/M500001wHLn740hViI.mp3?guid=0000000059a75988000001879df3d79f&vkey=6141AC992C75D4E9E86412929EE02E965F70487A11046098E6A2A59C88EC6BD53636BA46B48995259D2E100CFC409060EC6260F2CABEF2EE&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:25 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:25 volumio volumio[12660]: info: CoreStateMachine::syncState stateService stop Apr 11 03:23:25 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:23:25 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:25 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:25 volumio volumio[12660]: info: CoreStateMachine::stPlaybackTimer Apr 11 03:23:25 volumio volumio[12660]: info: ------------------------------ 4ms Apr 11 03:23:25 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:25 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:25 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:25 volumio volumio[12660]: info: Clearing queue after UPNP request Apr 11 03:23:26 volumio volumio[12660]: info: CoreStateMachine::ClearQueue Apr 11 03:23:26 volumio volumio[12660]: info: CoreStateMachine::stop Apr 11 03:23:26 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 03:23:26 volumio volumio[12660]: verbose: UNSET VOLATILE Apr 11 03:23:26 volumio volumio[12660]: info: CorePlayQueue::clearPlayQueue Apr 11 03:23:26 volumio volumio[12660]: info: CorePlayQueue::saveQueue Apr 11 03:23:26 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:26 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:26 volumio volumio[12660]: info: CoreCommandRouter::volumioPushQueue Apr 11 03:23:26 volumio volumio[12660]: info: Apr 11 03:23:26 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:26 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:26 volumio volumio[12660]: info: Starting UPNP Playback Apr 11 03:23:26 volumio volumio[12660]: info: Preparing playback through UPNP Apr 11 03:23:26 volumio volumio[12660]: info: CoreCommandRouter::volumioGetState Apr 11 03:23:26 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:23:26 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 03:23:26 volumio volumio[12660]: error: updateQueue error: null Apr 11 03:23:26 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:26 volumio volumio[12660]: info: Apr 11 03:23:26 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:26 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:26 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:26 volumio volumio[12660]: info: Apr 11 03:23:26 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:26 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:26 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:26 volumio volumio[12660]: info: Apr 11 03:23:26 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:26 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:26 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:23:26 volumio volumio[12660]: info: Apr 11 03:23:26 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:26 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:26 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:26 volumio volumio[12660]: info: Apr 11 03:23:26 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:26 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:26 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:23:26 volumio volumio[12660]: info: Apr 11 03:23:26 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:26 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:26 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:27 volumio volumio[12660]: info: Apr 11 03:23:27 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:23:27 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:27 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:27 volumio volumio[12660]: info: Apr 11 03:23:27 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:23:27 volumio volumio[12660]: info: sendMpdCommand status took 0 milliseconds Apr 11 03:23:27 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:27 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:27 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:27 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:27 volumio volumio[12660]: info: Apr 11 03:23:27 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:27 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:27 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:23:27 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 03:23:27 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:27 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:27 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:27 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:27 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:23:27 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"山河记","artist":"周小一","album":"山河记","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500003FoTAP02bqOe.mp3?guid=0000000059a75988000001879df3d79f&vkey=F00E0ED27B9DAA64886574A7449B60E90EE1CC464205C3AE3EC4B894099FBCF79E3DEFC5C532632E34B8EF51E4F53CC6B5A7F9C67D3C7DF6&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:27 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:27 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:23:27 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus stop Apr 11 03:23:27 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:27 volumio volumio[12660]: info: ------------------------------ 4ms Apr 11 03:23:27 volumio volumio[12660]: info: ------------------------------ 3ms Apr 11 03:23:27 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 03:23:27 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:27 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:27 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:23:27 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"山河记","artist":"周小一","album":"山河记","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500003FoTAP02bqOe.mp3?guid=0000000059a75988000001879df3d79f&vkey=F00E0ED27B9DAA64886574A7449B60E90EE1CC464205C3AE3EC4B894099FBCF79E3DEFC5C532632E34B8EF51E4F53CC6B5A7F9C67D3C7DF6&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:27 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:27 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:23:27 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:23:27 volumio volumio[12660]: info: Received an update from plugin. extracting info from payload Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 03:23:27 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:27 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:27 volumio volumio[12660]: info: ------------------------------ 7ms Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:27 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:27 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:27 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:27 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:27 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:36 volumio volumio[12660]: info: Apr 11 03:23:36 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:23:36 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:36 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:36 volumio volumio[12660]: info: sendMpdCommand status took 0 milliseconds Apr 11 03:23:36 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:36 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:36 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 03:23:36 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:36 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:36 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:36 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:23:36 volumio volumio[12660]: 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://182.242.50.152/amobile.music.tc.qq.com/M500003FoTAP02bqOe.mp3?guid=0000000059a75988000001879df3d79f&vkey=F00E0ED27B9DAA64886574A7449B60E90EE1CC464205C3AE3EC4B894099FBCF79E3DEFC5C532632E34B8EF51E4F53CC6B5A7F9C67D3C7DF6&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:36 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:36 volumio volumio[12660]: info: CoreStateMachine::syncState stateService stop Apr 11 03:23:36 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:36 volumio volumio[12660]: info: CoreStateMachine::stPlaybackTimer Apr 11 03:23:36 volumio volumio[12660]: info: ------------------------------ 5ms Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:36 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:36 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:36 volumio volumio[12660]: info: Clearing queue after UPNP request Apr 11 03:23:36 volumio volumio[12660]: info: CoreStateMachine::ClearQueue Apr 11 03:23:36 volumio volumio[12660]: info: CoreStateMachine::stop Apr 11 03:23:36 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 03:23:36 volumio volumio[12660]: verbose: UNSET VOLATILE Apr 11 03:23:36 volumio volumio[12660]: info: CorePlayQueue::clearPlayQueue Apr 11 03:23:36 volumio volumio[12660]: info: CorePlayQueue::saveQueue Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::volumioPushQueue Apr 11 03:23:36 volumio volumio[12660]: info: Apr 11 03:23:36 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:36 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:36 volumio volumio[12660]: error: updateQueue error: null Apr 11 03:23:36 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:36 volumio volumio[12660]: info: Starting UPNP Playback Apr 11 03:23:36 volumio volumio[12660]: info: Preparing playback through UPNP Apr 11 03:23:36 volumio volumio[12660]: info: CoreCommandRouter::volumioGetState Apr 11 03:23:36 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:23:36 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 03:23:37 volumio volumio[12660]: info: Apr 11 03:23:37 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:37 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:37 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:23:37 volumio volumio[12660]: info: Apr 11 03:23:37 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:37 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:37 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:37 volumio volumio[12660]: info: Apr 11 03:23:37 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:37 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:37 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:37 volumio volumio[12660]: info: Apr 11 03:23:37 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:37 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:37 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:37 volumio volumio[12660]: info: Apr 11 03:23:37 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:37 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:37 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:37 volumio volumio[12660]: info: Apr 11 03:23:37 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:37 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:37 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:23:38 volumio volumio[12660]: info: Apr 11 03:23:38 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:23:38 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:38 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:38 volumio volumio[12660]: info: Apr 11 03:23:38 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:23:38 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:23:38 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:38 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:38 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:38 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:38 volumio volumio[12660]: info: Apr 11 03:23:38 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:38 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:38 volumio volumio[12660]: info: sendMpdCommand status took 0 milliseconds Apr 11 03:23:38 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 03:23:38 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:38 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:38 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:38 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:38 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:23:38 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"凤求凰","artist":"周小一","album":"凤求凰","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500000lSvJZ3gsXj5.mp3?guid=0000000059a75988000001879df3d79f&vkey=A42DC142A9F4078F3B1F899CB9F41BC2A783F372F48835378C111251BEAB1AA843A799F907A5BC29C60FB0A211F1D881707F72D13A9823C2&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:38 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:38 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:23:38 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus stop Apr 11 03:23:38 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:38 volumio volumio[12660]: info: ------------------------------ 3ms Apr 11 03:23:38 volumio volumio[12660]: info: ------------------------------ 2ms Apr 11 03:23:38 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 03:23:38 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:38 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:38 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:23:38 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"凤求凰","artist":"周小一","album":"凤求凰","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500000lSvJZ3gsXj5.mp3?guid=0000000059a75988000001879df3d79f&vkey=A42DC142A9F4078F3B1F899CB9F41BC2A783F372F48835378C111251BEAB1AA843A799F907A5BC29C60FB0A211F1D881707F72D13A9823C2&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:38 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:38 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:23:38 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:23:38 volumio volumio[12660]: info: Received an update from plugin. extracting info from payload Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 03:23:38 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:38 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:38 volumio volumio[12660]: info: ------------------------------ 4ms Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:38 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:38 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:38 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:38 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:38 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:51 volumio volumio[12660]: info: Apr 11 03:23:51 volumio volumio[12660]: ---------------------------- MPD announces state update: update Apr 11 03:23:51 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:51 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:23:51 volumio volumio[12660]: info: sendMpdCommand status took 0 milliseconds Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:51 volumio volumio[12660]: info: Command Router : Notfying DB Updatetrue Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::Close All Modals sent Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:51 volumio volumio[12660]: info: MPD Database updated - AlbumList cache refreshed Apr 11 03:23:51 volumio volumio[12660]: info: Apr 11 03:23:51 volumio volumio[12660]: ---------------------------- MPD announces state update: database Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:51 volumio volumio[12660]: info: Apr 11 03:23:51 volumio volumio[12660]: ---------------------------- MPD announces state update: update Apr 11 03:23:51 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:51 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:51 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:51 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:51 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:23:51 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13978,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"凤求凰","artist":"周小一","album":"凤求凰","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500000lSvJZ3gsXj5.mp3?guid=0000000059a75988000001879df3d79f&vkey=A42DC142A9F4078F3B1F899CB9F41BC2A783F372F48835378C111251BEAB1AA843A799F907A5BC29C60FB0A211F1D881707F72D13A9823C2&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:51 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:23:51 volumio volumio[12660]: info: Received an update from plugin. extracting info from payload Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:51 volumio volumio[12660]: info: ------------------------------ 105ms Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: sendMpdCommand status took 21 milliseconds Apr 11 03:23:51 volumio volumio[12660]: info: sendMpdCommand status took 21 milliseconds Apr 11 03:23:51 volumio volumio[12660]: info: sendMpdCommand status took 21 milliseconds Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:51 volumio volumio[12660]: info: Command Router : Notfying DB Updatefalse Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::Close All Modals sent Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:51 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 03:23:51 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:51 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:51 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:51 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:23:51 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14106,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"凤求凰","artist":"周小一","album":"凤求凰","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500000lSvJZ3gsXj5.mp3?guid=0000000059a75988000001879df3d79f&vkey=A42DC142A9F4078F3B1F899CB9F41BC2A783F372F48835378C111251BEAB1AA843A799F907A5BC29C60FB0A211F1D881707F72D13A9823C2&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:51 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:23:51 volumio volumio[12660]: info: Received an update from plugin. extracting info from payload Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:51 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:51 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:23:51 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14106,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"凤求凰","artist":"周小一","album":"凤求凰","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500000lSvJZ3gsXj5.mp3?guid=0000000059a75988000001879df3d79f&vkey=A42DC142A9F4078F3B1F899CB9F41BC2A783F372F48835378C111251BEAB1AA843A799F907A5BC29C60FB0A211F1D881707F72D13A9823C2&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:51 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:23:51 volumio volumio[12660]: info: Received an update from plugin. extracting info from payload Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:51 volumio volumio[12660]: info: ------------------------------ 32ms Apr 11 03:23:51 volumio volumio[12660]: info: ------------------------------ 32ms Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:51 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:51 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:57 volumio volumio[12660]: info: Apr 11 03:23:57 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:23:57 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:57 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:57 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:23:57 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:57 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:57 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 03:23:57 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:57 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:57 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:57 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:23:57 volumio volumio[12660]: 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://182.242.50.152/amobile.music.tc.qq.com/M500000lSvJZ3gsXj5.mp3?guid=0000000059a75988000001879df3d79f&vkey=A42DC142A9F4078F3B1F899CB9F41BC2A783F372F48835378C111251BEAB1AA843A799F907A5BC29C60FB0A211F1D881707F72D13A9823C2&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:57 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:57 volumio volumio[12660]: info: CoreStateMachine::syncState stateService stop Apr 11 03:23:57 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:57 volumio volumio[12660]: info: CoreStateMachine::stPlaybackTimer Apr 11 03:23:57 volumio volumio[12660]: info: ------------------------------ 4ms Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:57 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:57 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:57 volumio volumio[12660]: info: Clearing queue after UPNP request Apr 11 03:23:57 volumio volumio[12660]: info: CoreStateMachine::ClearQueue Apr 11 03:23:57 volumio volumio[12660]: info: CoreStateMachine::stop Apr 11 03:23:57 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 03:23:57 volumio volumio[12660]: verbose: UNSET VOLATILE Apr 11 03:23:57 volumio volumio[12660]: info: CorePlayQueue::clearPlayQueue Apr 11 03:23:57 volumio volumio[12660]: info: CorePlayQueue::saveQueue Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::volumioPushQueue Apr 11 03:23:57 volumio volumio[12660]: info: Apr 11 03:23:57 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:57 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:57 volumio volumio[12660]: error: updateQueue error: null Apr 11 03:23:57 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:23:57 volumio volumio[12660]: info: Starting UPNP Playback Apr 11 03:23:57 volumio volumio[12660]: info: Preparing playback through UPNP Apr 11 03:23:57 volumio volumio[12660]: info: CoreCommandRouter::volumioGetState Apr 11 03:23:57 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:23:57 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 03:23:58 volumio volumio[12660]: info: Apr 11 03:23:58 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:58 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:58 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:23:58 volumio volumio[12660]: info: Apr 11 03:23:58 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:58 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:58 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:58 volumio volumio[12660]: info: Apr 11 03:23:58 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:58 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:58 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:23:58 volumio volumio[12660]: info: Apr 11 03:23:58 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:58 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:58 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:58 volumio volumio[12660]: info: Apr 11 03:23:58 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:58 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:58 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:58 volumio volumio[12660]: info: Apr 11 03:23:58 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:58 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:58 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:23:58 volumio volumio[12660]: info: Apr 11 03:23:58 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:23:58 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:58 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:58 volumio volumio[12660]: info: Apr 11 03:23:58 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:23:58 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:23:58 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:23:58 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:23:58 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:58 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:58 volumio volumio[12660]: info: Apr 11 03:23:58 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:23:58 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:23:58 volumio volumio[12660]: info: sendMpdCommand status took 2 milliseconds Apr 11 03:23:58 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 03:23:58 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:23:58 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:23:58 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:58 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:58 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:23:58 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":176,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"武林萌主","artist":"浣语","album":"武林萌主","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500004J3Wdu1tpmuH.mp3?guid=0000000059a75988000001879df3d79f&vkey=8950F61A6F9127755611D5111F439E3C4D25CC6AC2CD610C676827B3B346AD7C10487C5D32329D03F8079593F75158149796968E857D8D72&uin=1152921504679429217&src=M500000vIKUX3kiLt1.mp3&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:58 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:58 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:23:58 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus stop Apr 11 03:23:58 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:58 volumio volumio[12660]: info: ------------------------------ 9ms Apr 11 03:23:58 volumio volumio[12660]: info: ------------------------------ 8ms Apr 11 03:23:58 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 11 03:23:58 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:23:58 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:23:58 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:23:58 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":176,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"武林萌主","artist":"浣语","album":"武林萌主","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500004J3Wdu1tpmuH.mp3?guid=0000000059a75988000001879df3d79f&vkey=8950F61A6F9127755611D5111F439E3C4D25CC6AC2CD610C676827B3B346AD7C10487C5D32329D03F8079593F75158149796968E857D8D72&uin=1152921504679429217&src=M500000vIKUX3kiLt1.mp3&redirect=1&fromtag=111042","trackType":""} Apr 11 03:23:58 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:23:58 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:23:58 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:23:58 volumio volumio[12660]: info: Received an update from plugin. extracting info from payload Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 03:23:58 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:58 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:23:58 volumio volumio[12660]: info: ------------------------------ 13ms Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:58 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:58 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:23:58 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:23:58 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:23:58 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:05 volumio volumio[12660]: info: Apr 11 03:24:05 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:24:05 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:05 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:05 volumio volumio[12660]: info: sendMpdCommand status took 0 milliseconds Apr 11 03:24:05 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:05 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:24:05 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 03:24:05 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:24:05 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:05 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:05 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:05 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:05 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:05 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:24:05 volumio volumio[12660]: 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://182.242.50.152/amobile.music.tc.qq.com/M500004J3Wdu1tpmuH.mp3?guid=0000000059a75988000001879df3d79f&vkey=8950F61A6F9127755611D5111F439E3C4D25CC6AC2CD610C676827B3B346AD7C10487C5D32329D03F8079593F75158149796968E857D8D72&uin=1152921504679429217&src=M500000vIKUX3kiLt1.mp3&redirect=1&fromtag=111042","trackType":""} Apr 11 03:24:05 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:05 volumio volumio[12660]: info: CoreStateMachine::syncState stateService stop Apr 11 03:24:05 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:24:05 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:05 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:05 volumio volumio[12660]: info: CoreStateMachine::stPlaybackTimer Apr 11 03:24:05 volumio volumio[12660]: info: ------------------------------ 4ms Apr 11 03:24:05 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:24:05 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:24:05 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:05 volumio volumio[12660]: info: Clearing queue after UPNP request Apr 11 03:24:06 volumio volumio[12660]: info: CoreStateMachine::ClearQueue Apr 11 03:24:06 volumio volumio[12660]: info: CoreStateMachine::stop Apr 11 03:24:06 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 03:24:06 volumio volumio[12660]: verbose: UNSET VOLATILE Apr 11 03:24:06 volumio volumio[12660]: info: CorePlayQueue::clearPlayQueue Apr 11 03:24:06 volumio volumio[12660]: info: CorePlayQueue::saveQueue Apr 11 03:24:06 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:06 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:06 volumio volumio[12660]: info: CoreCommandRouter::volumioPushQueue Apr 11 03:24:06 volumio volumio[12660]: info: Apr 11 03:24:06 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:06 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:06 volumio volumio[12660]: error: updateQueue error: null Apr 11 03:24:06 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:06 volumio volumio[12660]: info: Starting UPNP Playback Apr 11 03:24:06 volumio volumio[12660]: info: Preparing playback through UPNP Apr 11 03:24:06 volumio volumio[12660]: info: CoreCommandRouter::volumioGetState Apr 11 03:24:06 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:24:06 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 03:24:06 volumio volumio[12660]: info: Apr 11 03:24:06 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:06 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:06 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:24:06 volumio volumio[12660]: info: Apr 11 03:24:06 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:06 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:06 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:06 volumio volumio[12660]: info: Apr 11 03:24:06 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:06 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:06 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:06 volumio volumio[12660]: info: Apr 11 03:24:06 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:06 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:06 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:06 volumio volumio[12660]: info: Apr 11 03:24:06 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:06 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:06 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:24:06 volumio volumio[12660]: info: Apr 11 03:24:06 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:06 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:06 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:07 volumio volumio[12660]: info: Apr 11 03:24:07 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:24:07 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:07 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:07 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:24:07 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:07 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:24:07 volumio volumio[12660]: info: Apr 11 03:24:07 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:07 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:07 volumio volumio[12660]: info: Apr 11 03:24:07 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:24:07 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:07 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:07 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 03:24:07 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:24:07 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:07 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:24:07 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"寸寸相思红","artist":"midi sing","album":"寸寸相思红","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500003fmojY1FUH6Z.mp3?guid=0000000059a75988000001879df3d79f&vkey=31B7161DA8E645BCF3B44A374628579AC8FA24B9A4026327240BE0530C351CD8FA311C240CFAE54AF73FA4A3A4DE43C5D4BD4CBE70DF3591&uin=1152921504679429217&src=M500003yIuzF1g1mOJ.mp3&redirect=1&fromtag=111042","trackType":""} Apr 11 03:24:07 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:07 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:24:07 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus stop Apr 11 03:24:07 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:07 volumio volumio[12660]: info: ------------------------------ 4ms Apr 11 03:24:07 volumio volumio[12660]: info: ------------------------------ 2ms Apr 11 03:24:07 volumio volumio[12660]: info: sendMpdCommand status took 2 milliseconds Apr 11 03:24:07 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:07 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:24:07 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 03:24:07 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:24:07 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:07 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:24:07 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"寸寸相思红","artist":"midi sing","album":"寸寸相思红","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500003fmojY1FUH6Z.mp3?guid=0000000059a75988000001879df3d79f&vkey=31B7161DA8E645BCF3B44A374628579AC8FA24B9A4026327240BE0530C351CD8FA311C240CFAE54AF73FA4A3A4DE43C5D4BD4CBE70DF3591&uin=1152921504679429217&src=M500003yIuzF1g1mOJ.mp3&redirect=1&fromtag=111042","trackType":""} Apr 11 03:24:07 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:07 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:24:07 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:24:07 volumio volumio[12660]: info: Received an update from plugin. extracting info from payload Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 03:24:07 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:07 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:07 volumio volumio[12660]: info: ------------------------------ 9ms Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:24:07 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:24:07 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:07 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:24:07 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:24:07 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:26 volumio volumio[12660]: info: Apr 11 03:24:26 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:24:26 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:26 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:26 volumio volumio[12660]: info: sendMpdCommand status took 0 milliseconds Apr 11 03:24:26 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:26 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:24:26 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 03:24:26 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:24:26 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:26 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:26 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:26 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:26 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:26 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:24:26 volumio volumio[12660]: 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":"midi sing","album":"寸寸相思红","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M500003fmojY1FUH6Z.mp3?guid=0000000059a75988000001879df3d79f&vkey=31B7161DA8E645BCF3B44A374628579AC8FA24B9A4026327240BE0530C351CD8FA311C240CFAE54AF73FA4A3A4DE43C5D4BD4CBE70DF3591&uin=1152921504679429217&src=M500003yIuzF1g1mOJ.mp3&redirect=1&fromtag=111042","trackType":""} Apr 11 03:24:26 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:26 volumio volumio[12660]: info: CoreStateMachine::syncState stateService stop Apr 11 03:24:26 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:24:26 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:26 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:26 volumio volumio[12660]: info: CoreStateMachine::stPlaybackTimer Apr 11 03:24:26 volumio volumio[12660]: info: ------------------------------ 5ms Apr 11 03:24:26 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:24:26 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:24:26 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:27 volumio volumio[12660]: info: Clearing queue after UPNP request Apr 11 03:24:27 volumio volumio[12660]: info: CoreStateMachine::ClearQueue Apr 11 03:24:27 volumio volumio[12660]: info: CoreStateMachine::stop Apr 11 03:24:27 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 03:24:27 volumio volumio[12660]: verbose: UNSET VOLATILE Apr 11 03:24:27 volumio volumio[12660]: info: CorePlayQueue::clearPlayQueue Apr 11 03:24:27 volumio volumio[12660]: info: CorePlayQueue::saveQueue Apr 11 03:24:27 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:27 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:27 volumio volumio[12660]: info: CoreCommandRouter::volumioPushQueue Apr 11 03:24:27 volumio volumio[12660]: info: Apr 11 03:24:27 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:27 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:27 volumio volumio[12660]: error: updateQueue error: null Apr 11 03:24:27 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:27 volumio volumio[12660]: info: Starting UPNP Playback Apr 11 03:24:27 volumio volumio[12660]: info: Preparing playback through UPNP Apr 11 03:24:27 volumio volumio[12660]: info: CoreCommandRouter::volumioGetState Apr 11 03:24:27 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:24:27 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 03:24:28 volumio volumio[12660]: info: Apr 11 03:24:28 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:28 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:28 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:24:28 volumio volumio[12660]: info: Apr 11 03:24:28 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:28 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:28 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:28 volumio volumio[12660]: info: Apr 11 03:24:28 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:28 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:28 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:28 volumio volumio[12660]: info: Apr 11 03:24:28 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:28 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:28 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:24:28 volumio volumio[12660]: info: Apr 11 03:24:28 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:28 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:28 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:28 volumio volumio[12660]: info: Apr 11 03:24:28 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:28 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:28 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:28 volumio volumio[12660]: info: Apr 11 03:24:28 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:24:28 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:28 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:28 volumio volumio[12660]: info: Apr 11 03:24:28 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:24:28 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:24:28 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:28 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:28 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:28 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:24:28 volumio volumio[12660]: info: Apr 11 03:24:28 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:28 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:28 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:24:28 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 03:24:28 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:28 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:24:28 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:24:28 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:28 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:24:28 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":198,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Winter, Fire and Snow","artist":"Anuna","album":"Winter Songs","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M5000020E71m0xJzeI.mp3?guid=0000000059a75988000001879df3d79f&vkey=6D6EE170DDEA906AE843772F9FB1E9C93D508973B8A3FA97C85C6986E6E7D4AC4DA43CDFF0B071A4A81E2FF67E84F1153D89CB08A914426D&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:24:28 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:28 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:24:28 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus stop Apr 11 03:24:28 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:28 volumio volumio[12660]: info: ------------------------------ 5ms Apr 11 03:24:28 volumio volumio[12660]: info: ------------------------------ 3ms Apr 11 03:24:28 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 03:24:28 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:24:28 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:28 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:24:28 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":198,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Winter, Fire and Snow","artist":"Anuna","album":"Winter Songs","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M5000020E71m0xJzeI.mp3?guid=0000000059a75988000001879df3d79f&vkey=6D6EE170DDEA906AE843772F9FB1E9C93D508973B8A3FA97C85C6986E6E7D4AC4DA43CDFF0B071A4A81E2FF67E84F1153D89CB08A914426D&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:24:28 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:28 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:24:28 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:24:28 volumio volumio[12660]: info: Received an update from plugin. extracting info from payload Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 03:24:28 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:28 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:28 volumio volumio[12660]: info: ------------------------------ 7ms Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:24:28 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:24:28 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:28 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:24:28 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:24:28 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:30 volumio smbd[1906]: [2024/04/11 03:24:30.231058, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 11 03:24:30 volumio smbd[1906]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 11 03:24:34 volumio volumio[12660]: info: Apr 11 03:24:34 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:24:34 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:34 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:34 volumio volumio[12660]: info: sendMpdCommand status took 0 milliseconds Apr 11 03:24:34 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:34 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:24:34 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 03:24:34 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:24:34 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:34 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:34 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:34 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:34 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:34 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:24:34 volumio volumio[12660]: 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":"Winter, Fire and Snow","artist":"Anuna","album":"Winter Songs","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M5000020E71m0xJzeI.mp3?guid=0000000059a75988000001879df3d79f&vkey=6D6EE170DDEA906AE843772F9FB1E9C93D508973B8A3FA97C85C6986E6E7D4AC4DA43CDFF0B071A4A81E2FF67E84F1153D89CB08A914426D&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:24:34 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:34 volumio volumio[12660]: info: CoreStateMachine::syncState stateService stop Apr 11 03:24:34 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:24:34 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:34 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:34 volumio volumio[12660]: info: CoreStateMachine::stPlaybackTimer Apr 11 03:24:34 volumio volumio[12660]: info: ------------------------------ 2ms Apr 11 03:24:34 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:24:34 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:24:34 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:34 volumio volumio[12660]: info: Clearing queue after UPNP request Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::ClearQueue Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::stop Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 03:24:35 volumio volumio[12660]: verbose: UNSET VOLATILE Apr 11 03:24:35 volumio volumio[12660]: info: CorePlayQueue::clearPlayQueue Apr 11 03:24:35 volumio volumio[12660]: info: CorePlayQueue::saveQueue Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushQueue Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:35 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:35 volumio volumio[12660]: info: Starting UPNP Playback Apr 11 03:24:35 volumio volumio[12660]: info: Preparing playback through UPNP Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioGetState Apr 11 03:24:35 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 03:24:35 volumio volumio[12660]: error: updateQueue error: null Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces state update: update Apr 11 03:24:35 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:35 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:24:35 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:35 volumio volumio[12660]: info: Command Router : Notfying DB Updatetrue Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::Close All Modals sent Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:35 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:24:35 volumio volumio[12660]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 11 03:24:35 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::syncState stateService stop Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus stop Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: No code Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 48ms Apr 11 03:24:35 volumio volumio[12660]: info: MPD Database updated - AlbumList cache refreshed Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces state update: database Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces state update: update Apr 11 03:24:35 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:35 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:35 volumio volumio[12660]: info: sendMpdCommand status took 17 milliseconds Apr 11 03:24:35 volumio volumio[12660]: info: sendMpdCommand status took 16 milliseconds Apr 11 03:24:35 volumio volumio[12660]: info: sendMpdCommand status took 16 milliseconds Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:35 volumio volumio[12660]: info: Command Router : Notfying DB Updatefalse Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::Close All Modals sent Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:35 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:35 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:24:35 volumio volumio[12660]: 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} Apr 11 03:24:35 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::syncState stateService stop Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus stop Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: No code Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: CorePlayQueue::getTrack 0 Apr 11 03:24:35 volumio volumio[12660]: 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} Apr 11 03:24:35 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::syncState stateService stop Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus stop Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: No code Apr 11 03:24:35 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:35 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 28ms Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 28ms Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:35 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:35 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:35 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:35 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 1ms Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:35 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:35 volumio volumio[12660]: info: Apr 11 03:24:35 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:35 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:35 volumio volumio[12660]: info: ------------------------------ 0ms Apr 11 03:24:36 volumio volumio[12660]: info: Apr 11 03:24:36 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:24:36 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:36 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:36 volumio volumio[12660]: info: sendMpdCommand status took 1 milliseconds Apr 11 03:24:36 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:36 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:24:36 volumio volumio[12660]: info: Apr 11 03:24:36 volumio volumio[12660]: ---------------------------- MPD announces system playlist update Apr 11 03:24:36 volumio volumio[12660]: info: Ignoring MPD Status Update Apr 11 03:24:36 volumio volumio[12660]: info: Apr 11 03:24:36 volumio volumio[12660]: ---------------------------- MPD announces state update: player Apr 11 03:24:36 volumio volumio[12660]: info: ControllerMpd::getState Apr 11 03:24:36 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand status Apr 11 03:24:36 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 11 03:24:36 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:24:36 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:36 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:24:36 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"笑傲江湖-琴箫合奏","artist":"心理情绪睡眠管理师","album":"中国古典音乐精选（古乐器古风）悠扬","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M5000043Ufre0weVQY.mp3?guid=0000000059a75988000001879df3d79f&vkey=1607C75478478F1DB1D5ACDFC4A8E779FC8926D17A18A9908CDDD58E2B9D9AE5D727F7C2C33436852EEB343B16099802C984992851123BAC&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:24:36 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:36 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:24:36 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus stop Apr 11 03:24:36 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:36 volumio volumio[12660]: info: ------------------------------ 3ms Apr 11 03:24:36 volumio volumio[12660]: info: ------------------------------ 2ms Apr 11 03:24:36 volumio volumio[12660]: info: sendMpdCommand status took 2 milliseconds Apr 11 03:24:36 volumio volumio[12660]: verbose: ControllerMpd::parseState Apr 11 03:24:36 volumio volumio[12660]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 03:24:36 volumio volumio[12660]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 03:24:36 volumio volumio[12660]: verbose: ControllerMpd::parseTrackInfo Apr 11 03:24:36 volumio volumio[12660]: info: ControllerMpd::pushState Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::servicePushState Apr 11 03:24:36 volumio volumio[12660]: verbose: In UPNP mode Apr 11 03:24:36 volumio volumio[12660]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"笑傲江湖-琴箫合奏","artist":"心理情绪睡眠管理师","album":"中国古典音乐精选（古乐器古风）悠扬","uri":"http://182.242.50.152/amobile.music.tc.qq.com/M5000043Ufre0weVQY.mp3?guid=0000000059a75988000001879df3d79f&vkey=1607C75478478F1DB1D5ACDFC4A8E779FC8926D17A18A9908CDDD58E2B9D9AE5D727F7C2C33436852EEB343B16099802C984992851123BAC&uin=1152921504679429217&redirect=1&fromtag=111042","trackType":""} Apr 11 03:24:36 volumio volumio[12660]: verbose: CURRENT POSITION 0 Apr 11 03:24:36 volumio volumio[12660]: info: CoreStateMachine::syncState stateService play Apr 11 03:24:36 volumio volumio[12660]: info: CoreStateMachine::syncState currentStatus play Apr 11 03:24:36 volumio volumio[12660]: info: Received an update from plugin. extracting info from payload Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 11 03:24:36 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:36 volumio volumio[12660]: info: CoreStateMachine::pushState Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::volumioPushState Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 03:24:36 volumio volumio[12660]: info: ------------------------------ 6ms Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:24:36 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:24:36 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:36 volumio volumio[12660]: info: CoreCommandRouter::volumioGetQueue Apr 11 03:24:36 volumio volumio[12660]: info: CoreStateMachine::getQueue Apr 11 03:24:36 volumio volumio[12660]: info: CorePlayQueue::getQueue Apr 11 03:24:42 volumio volumio[12660]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 03:24:42 volumio volumio[12660]: { Error: connect ETIMEDOUT 179.60.193.9:443 Apr 11 03:24:42 volumio volumio[12660]: at Object._errnoException (util.js:1022:11) Apr 11 03:24:42 volumio volumio[12660]: at _exceptionWithHostPort (util.js:1044:20) Apr 11 03:24:42 volumio volumio[12660]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 11 03:24:42 volumio volumio[12660]: code: 'ETIMEDOUT', Apr 11 03:24:42 volumio volumio[12660]: errno: 'ETIMEDOUT', Apr 11 03:24:42 volumio volumio[12660]: syscall: 'connect', Apr 11 03:24:42 volumio volumio[12660]: address: '179.60.193.9', Apr 11 03:24:42 volumio volumio[12660]: port: 443 } Apr 11 03:24:42 volumio volumio[12660]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 03:24:42 volumio sudo[17036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 03:23 Apr 11 03:24:42 volumio sudo[17036]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"