-- Logs begin at Sun 2024-04-28 02:34:15 UTC, end at Mon 2024-04-29 05:16:49 UTC. -- Apr 29 05:15:01 volumio volumio[2918]: 93 Apr 29 05:15:01 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:01 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:01 volumio volumio[4503]: info: Apr 29 05:15:01 volumio volumio[4503]: ---------------------------- Client requests Volume 93 Apr 29 05:15:01 volumio volumio[4503]: info: VolumeController::SetAlsaVolume93 Apr 29 05:15:01 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:01 volumio volumio[2918]: [155B blob data] Apr 29 05:15:01 volumio volumio[2918]: {"time":1714367701649,"response":"volume Success"}93 Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:01 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:01 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:01 volumio volumio[4503]: info: Apr 29 05:15:01 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:01 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:01 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:01 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:15:01 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:01 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:01 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 05:15:01 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:01 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:01 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:01 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6478,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:01 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:01 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:01 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:01 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:01 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:01 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:01 volumio volumio[4503]: info: ------------------------------ 23ms Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:01 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:01 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:01 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:01 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:01 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[2918]: 87 Apr 29 05:15:02 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:02 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- Client requests Volume 87 Apr 29 05:15:02 volumio volumio[4503]: info: VolumeController::SetAlsaVolume87 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[2918]: [155B blob data] Apr 29 05:15:02 volumio volumio[2918]: {"time":1714367702082,"response":"volume Success"}87 Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand status took 1 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:02 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:02 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6884,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:02 volumio volumio[2918]: 56 Apr 29 05:15:02 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:02 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: ------------------------------ 60ms Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:02 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- Client requests Volume 56 Apr 29 05:15:02 volumio volumio[4503]: info: VolumeController::SetAlsaVolume56 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[2918]: [155B blob data] Apr 29 05:15:02 volumio volumio[2918]: {"time":1714367702224,"response":"volume Success"}56 Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[2918]: 37 Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:02 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:02 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7082,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:02 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:02 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: ------------------------------ 42ms Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:02 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- Client requests Volume 37 Apr 29 05:15:02 volumio volumio[4503]: info: VolumeController::SetAlsaVolume37 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[2918]: [155B blob data] Apr 29 05:15:02 volumio volumio[2918]: {"time":1714367702389,"response":"volume Success"}37 Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:02 volumio volumio[2918]: 18 Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:02 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:02 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7279,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:02 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:02 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: ------------------------------ 55ms Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:02 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- Client requests Volume 18 Apr 29 05:15:02 volumio volumio[4503]: info: VolumeController::SetAlsaVolume18 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[2918]: [155B blob data] Apr 29 05:15:02 volumio volumio[2918]: {"time":1714367702581,"response":"volume Success"}18 Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[2918]: 0 Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand status took 6 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:02 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:02 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7383,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:02 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:02 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: ------------------------------ 48ms Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:02 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- Client requests Volume 1 Apr 29 05:15:02 volumio volumio[4503]: info: VolumeController::SetAlsaVolume1 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[2918]: [155B blob data] Apr 29 05:15:02 volumio volumio[2918]: {"time":1714367702752,"response":"volume Success"}1 Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: Apr 29 05:15:02 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:02 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 29 05:15:02 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:02 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:02 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:02 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7581,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:02 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:02 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:02 volumio volumio[4503]: info: ------------------------------ 35ms Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:02 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:02 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:39 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:15:39 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:39 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:15:39 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:15:39 volumio volumio[4503]: info: Apr 29 05:15:39 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:39 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:39 volumio volumio[4503]: info: Apr 29 05:15:39 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:39 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:39 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:39 volumio volumio[4503]: info: sendMpdCommand stop took 45 milliseconds Apr 29 05:15:39 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:15:39 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:15:39 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:15:39 volumio volumio[4503]: info: ------------------------------ 5ms Apr 29 05:15:39 volumio volumio[4503]: info: sendMpdCommand status took 4 milliseconds Apr 29 05:15:39 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:39 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:39 volumio volumio[4503]: 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 29 05:15:39 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:39 volumio volumio[4503]: info: No code Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:39 volumio volumio[4503]: info: ------------------------------ 21ms Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:39 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:39 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:39 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:40 volumio volumio[4503]: info: Apr 29 05:15:40 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:40 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:40 volumio volumio[4503]: info: Apr 29 05:15:40 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:40 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:40 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:15:40 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:40 volumio volumio[4503]: info: Apr 29 05:15:40 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:40 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:40 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:40 volumio volumio[4503]: info: Apr 29 05:15:40 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:40 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:40 volumio volumio[4503]: info: Apr 29 05:15:40 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:40 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:40 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:15:40 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:40 volumio volumio[4503]: info: Apr 29 05:15:40 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:40 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:40 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:40 volumio volumio[4503]: info: Apr 29 05:15:40 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:40 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:15:40 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:40 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:40 volumio volumio[4503]: info: Apr 29 05:15:40 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:40 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:40 volumio volumio[4503]: info: Apr 29 05:15:40 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:40 volumio volumio[4503]: info: sendMpdCommand status took 13 milliseconds Apr 29 05:15:40 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:40 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:40 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:40 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:40 volumio volumio[4503]: info: ------------------------------ 5ms Apr 29 05:15:40 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:15:40 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 05:15:40 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:40 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:40 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:40 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:40 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:40 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:40 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:40 volumio volumio[4503]: info: ------------------------------ 29ms Apr 29 05:15:40 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 29 05:15:40 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:40 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:40 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:40 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:40 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:40 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:40 volumio volumio[4503]: info: ------------------------------ 31ms Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:40 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:40 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:40 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:40 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:40 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:41 volumio volumio[2918]: 6 Apr 29 05:15:41 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:41 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:41 volumio volumio[4503]: info: Apr 29 05:15:41 volumio volumio[4503]: ---------------------------- Client requests Volume 6 Apr 29 05:15:41 volumio volumio[4503]: info: VolumeController::SetAlsaVolume6 Apr 29 05:15:41 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:41 volumio volumio[2918]: [155B blob data] Apr 29 05:15:41 volumio volumio[2918]: {"time":1714367741876,"response":"volume Success"}6 Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:41 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:41 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:41 volumio volumio[4503]: info: Apr 29 05:15:41 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:41 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:41 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:41 volumio volumio[4503]: info: sendMpdCommand status took 1 milliseconds Apr 29 05:15:41 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:41 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:41 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 29 05:15:41 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:41 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:41 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:41 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1880,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:41 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:41 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:41 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:41 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:41 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:41 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:41 volumio volumio[4503]: info: ------------------------------ 21ms Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:41 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:41 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:41 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:41 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:41 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:43 volumio volumio[2918]: 12 Apr 29 05:15:43 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:43 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:43 volumio volumio[4503]: info: Apr 29 05:15:43 volumio volumio[4503]: ---------------------------- Client requests Volume 12 Apr 29 05:15:43 volumio volumio[4503]: info: VolumeController::SetAlsaVolume12 Apr 29 05:15:43 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:43 volumio volumio[2918]: [155B blob data] Apr 29 05:15:43 volumio volumio[2918]: {"time":1714367743753,"response":"volume Success"}12 Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:43 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:43 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:43 volumio volumio[4503]: info: Apr 29 05:15:43 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:43 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:43 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:43 volumio volumio[4503]: info: sendMpdCommand status took 1 milliseconds Apr 29 05:15:43 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:43 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:43 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 05:15:43 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:43 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:43 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:43 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3680,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:43 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:43 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:43 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:43 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:43 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:43 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:43 volumio volumio[4503]: info: ------------------------------ 23ms Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:43 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:43 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:43 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:43 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:43 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:44 volumio volumio[2918]: 18 Apr 29 05:15:44 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:44 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:44 volumio volumio[4503]: info: Apr 29 05:15:44 volumio volumio[4503]: ---------------------------- Client requests Volume 18 Apr 29 05:15:44 volumio volumio[4503]: info: VolumeController::SetAlsaVolume18 Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:44 volumio volumio[2918]: [155B blob data] Apr 29 05:15:44 volumio volumio[2918]: {"time":1714367744564,"response":"volume Success"}18 Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: Apr 29 05:15:44 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:44 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:44 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:44 volumio volumio[4503]: info: sendMpdCommand status took 1 milliseconds Apr 29 05:15:44 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:44 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:44 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 29 05:15:44 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:44 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:44 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:44 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4585,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:44 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:44 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:44 volumio volumio[4503]: info: ------------------------------ 28ms Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:44 volumio volumio[2918]: 25 Apr 29 05:15:44 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:44 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:44 volumio volumio[4503]: info: Apr 29 05:15:44 volumio volumio[4503]: ---------------------------- Client requests Volume 25 Apr 29 05:15:44 volumio volumio[4503]: info: VolumeController::SetAlsaVolume25 Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:44 volumio volumio[2918]: [155B blob data] Apr 29 05:15:44 volumio volumio[2918]: {"time":1714367744827,"response":"volume Success"}25 Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: Apr 29 05:15:44 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:44 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:44 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:44 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:15:44 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:44 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:44 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 05:15:44 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:44 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:44 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:44 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4783,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:44 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:44 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:44 volumio volumio[4503]: info: ------------------------------ 27ms Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:44 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:44 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:45 volumio volumio[2918]: 31 Apr 29 05:15:45 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:45 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:45 volumio volumio[4503]: info: Apr 29 05:15:45 volumio volumio[4503]: ---------------------------- Client requests Volume 31 Apr 29 05:15:45 volumio volumio[4503]: info: VolumeController::SetAlsaVolume31 Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:45 volumio volumio[2918]: [155B blob data] Apr 29 05:15:45 volumio volumio[2918]: {"time":1714367745233,"response":"volume Success"}31 Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: Apr 29 05:15:45 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:45 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:45 volumio volumio[4503]: info: sendMpdCommand status took 3 milliseconds Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:45 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:45 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:45 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:45 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5178,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:45 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:45 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:45 volumio volumio[4503]: info: ------------------------------ 27ms Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:45 volumio volumio[2918]: 62 Apr 29 05:15:45 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:45 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:45 volumio volumio[4503]: info: Apr 29 05:15:45 volumio volumio[4503]: ---------------------------- Client requests Volume 62 Apr 29 05:15:45 volumio volumio[4503]: info: VolumeController::SetAlsaVolume62 Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:45 volumio volumio[2918]: [155B blob data] Apr 29 05:15:45 volumio volumio[2918]: {"time":1714367745385,"response":"volume Success"}62 Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: Apr 29 05:15:45 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:45 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:45 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:45 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:45 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:45 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:45 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5387,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:45 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:45 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:45 volumio volumio[4503]: info: ------------------------------ 20ms Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:45 volumio volumio[2918]: 81 Apr 29 05:15:45 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 05:15:45 volumio volumio[2918]: Dload Upload Total Spent Left Speed Apr 29 05:15:45 volumio volumio[4503]: info: Apr 29 05:15:45 volumio volumio[4503]: ---------------------------- Client requests Volume 81 Apr 29 05:15:45 volumio volumio[4503]: info: VolumeController::SetAlsaVolume81 Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:45 volumio volumio[2918]: [155B blob data] Apr 29 05:15:45 volumio volumio[2918]: {"time":1714367745507,"response":"volume Success"}81 Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: Apr 29 05:15:45 volumio volumio[4503]: ---------------------------- MPD announces state update: mixer Apr 29 05:15:45 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:45 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:45 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 29 05:15:45 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:45 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:45 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:45 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5479,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A place called Morning Forest","artist":"Bill Douglas","album":"Kucshel Klassik Vol.06","uri":"http://isure6.stream.qqmusic.qq.com/M500000Im8M61iXCot.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=61C67FC2FE1257371BCE81B0030C38C88259B1BB8F74A3ABBB95B1030C62CECCB09196C3396412A2167FD1BF5D3BE3EE8F5510D06402059C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:45 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:45 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:45 volumio volumio[4503]: info: ------------------------------ 20ms Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:45 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:45 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:48 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:15:48 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:48 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:15:48 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:15:48 volumio volumio[4503]: info: Apr 29 05:15:48 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:48 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:48 volumio volumio[4503]: info: Apr 29 05:15:48 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:48 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:48 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:48 volumio volumio[4503]: info: sendMpdCommand stop took 21 milliseconds Apr 29 05:15:48 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:15:48 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:15:48 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:15:48 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:15:48 volumio volumio[4503]: info: sendMpdCommand status took 4 milliseconds Apr 29 05:15:48 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:48 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:48 volumio volumio[4503]: 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 29 05:15:48 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:48 volumio volumio[4503]: info: No code Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:48 volumio volumio[4503]: info: ------------------------------ 35ms Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:48 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:48 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:48 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:49 volumio volumio[4503]: info: Apr 29 05:15:49 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:49 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:49 volumio volumio[4503]: info: Apr 29 05:15:49 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:49 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:49 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:49 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:49 volumio volumio[4503]: info: Apr 29 05:15:49 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:49 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:49 volumio volumio[4503]: info: Apr 29 05:15:49 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:49 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:49 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:49 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:15:49 volumio volumio[4503]: info: Apr 29 05:15:49 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:49 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:49 volumio volumio[4503]: info: Apr 29 05:15:49 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:49 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:49 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:15:49 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:49 volumio volumio[4503]: info: Apr 29 05:15:49 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:49 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:15:49 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:49 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:49 volumio volumio[4503]: info: Apr 29 05:15:49 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:49 volumio volumio[4503]: info: sendMpdCommand status took 13 milliseconds Apr 29 05:15:49 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:49 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:49 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:49 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:49 volumio volumio[4503]: info: Apr 29 05:15:49 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:49 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:49 volumio volumio[4503]: info: sendMpdCommand status took 3 milliseconds Apr 29 05:15:49 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 29 05:15:49 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:49 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:49 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:49 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:49 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:49 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"西西里舞曲","artist":"Classical Artists","album":"越听越聪明 2","uri":"http://isure6.stream.qqmusic.qq.com/M5000011QLGV34COm1.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=BB9F6F7F74BC802DC138DA7AE53346BCFFB83D6A433527815905ACC2D5AA10A1F27FB4250A1FFD27A7EFDAFAE1948F19166023E3C19F78A2&uin=1152921504689611133&src=M500004PKGec38l66T.mp3&redirect=1&fromtag=101982","trackType":""} Apr 29 05:15:49 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:49 volumio volumio[4503]: info: ------------------------------ 42ms Apr 29 05:15:49 volumio volumio[4503]: info: ------------------------------ 26ms Apr 29 05:15:49 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 29 05:15:49 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:49 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:49 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:49 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"西西里舞曲","artist":"Classical Artists","album":"越听越聪明 2","uri":"http://isure6.stream.qqmusic.qq.com/M5000011QLGV34COm1.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=BB9F6F7F74BC802DC138DA7AE53346BCFFB83D6A433527815905ACC2D5AA10A1F27FB4250A1FFD27A7EFDAFAE1948F19166023E3C19F78A2&uin=1152921504689611133&src=M500004PKGec38l66T.mp3&redirect=1&fromtag=101982","trackType":""} Apr 29 05:15:49 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:49 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:49 volumio volumio[4503]: info: ------------------------------ 50ms Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:49 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:49 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:49 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:49 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:49 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:49 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:15:50 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:50 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:15:50 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:15:50 volumio volumio[4503]: info: Apr 29 05:15:50 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:50 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:50 volumio volumio[4503]: info: Apr 29 05:15:50 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:50 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:50 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:50 volumio volumio[4503]: info: sendMpdCommand stop took 85 milliseconds Apr 29 05:15:50 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:15:50 volumio volumio[4503]: info: ------------------------------ 7ms Apr 29 05:15:50 volumio volumio[4503]: info: sendMpdCommand status took 4 milliseconds Apr 29 05:15:50 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:50 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:50 volumio volumio[4503]: 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 29 05:15:50 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:50 volumio volumio[4503]: info: No code Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:50 volumio volumio[4503]: info: ------------------------------ 48ms Apr 29 05:15:50 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:15:50 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:15:50 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:15:50 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:50 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:15:50 volumio volumio[4503]: info: Apr 29 05:15:50 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:50 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:50 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:15:50 volumio volumio[4503]: info: Apr 29 05:15:50 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:50 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:50 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:15:50 volumio volumio[4503]: info: Apr 29 05:15:50 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:50 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:50 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:15:50 volumio volumio[4503]: info: Apr 29 05:15:50 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:50 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:50 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:15:50 volumio volumio[4503]: info: Apr 29 05:15:50 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:50 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:50 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:15:50 volumio volumio[4503]: info: Apr 29 05:15:50 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:50 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:50 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:15:54 volumio volumio[4503]: info: Apr 29 05:15:54 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:54 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:54 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:54 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:15:54 volumio volumio[4503]: info: Apr 29 05:15:54 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:54 volumio volumio[4503]: info: sendMpdCommand status took 14 milliseconds Apr 29 05:15:54 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:54 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:54 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:54 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:54 volumio volumio[4503]: info: Apr 29 05:15:54 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:54 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:54 volumio volumio[4503]: info: sendMpdCommand status took 6 milliseconds Apr 29 05:15:54 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 29 05:15:54 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:54 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:54 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:54 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:54 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:54 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Liebesfreud","artist":"Joshua Bell","album":"Essential Violin","uri":"http://isure6.stream.qqmusic.qq.com/M500002KFtLy1Redug.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=A9B4D6BB419F166E83D582D26EF6845ABC983E466D377F6B0012DF1C14DBD10660565389C6FE8FE2C2693688416E78FE3172B15D18D34B02&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:54 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:54 volumio volumio[4503]: info: ------------------------------ 37ms Apr 29 05:15:54 volumio volumio[4503]: info: ------------------------------ 17ms Apr 29 05:15:54 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 29 05:15:54 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:54 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:54 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:54 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Liebesfreud","artist":"Joshua Bell","album":"Essential Violin","uri":"http://isure6.stream.qqmusic.qq.com/M500002KFtLy1Redug.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=A9B4D6BB419F166E83D582D26EF6845ABC983E466D377F6B0012DF1C14DBD10660565389C6FE8FE2C2693688416E78FE3172B15D18D34B02&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:54 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:54 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:54 volumio volumio[4503]: info: ------------------------------ 46ms Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:54 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:54 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:54 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:54 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:54 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:54 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:15:55 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:55 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:55 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:55 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:55 volumio volumio[4503]: info: sendMpdCommand stop took 73 milliseconds Apr 29 05:15:55 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:15:55 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:15:55 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:15:55 volumio volumio[4503]: info: sendMpdCommand status took 3 milliseconds Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:55 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:55 volumio volumio[4503]: 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 29 05:15:55 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:55 volumio volumio[4503]: info: No code Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 22ms Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:55 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:55 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:55 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:55 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:55 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:55 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:55 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:55 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:15:55 volumio volumio[4503]: info: sendMpdCommand status took 13 milliseconds Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:55 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:55 volumio volumio[4503]: info: Apr 29 05:15:55 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:55 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:55 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 29 05:15:55 volumio volumio[4503]: info: sendMpdCommand status took 3 milliseconds Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:55 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:55 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:55 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"西西里舞曲","artist":"Classical Artists","album":"越听越聪明 2","uri":"http://isure6.stream.qqmusic.qq.com/M5000011QLGV34COm1.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=BB9F6F7F74BC802DC138DA7AE53346BCFFB83D6A433527815905ACC2D5AA10A1F27FB4250A1FFD27A7EFDAFAE1948F19166023E3C19F78A2&uin=1152921504689611133&src=M500004PKGec38l66T.mp3&redirect=1&fromtag=101982","trackType":""} Apr 29 05:15:55 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 38ms Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 21ms Apr 29 05:15:55 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 29 05:15:55 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:55 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:55 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:55 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"西西里舞曲","artist":"Classical Artists","album":"越听越聪明 2","uri":"http://isure6.stream.qqmusic.qq.com/M5000011QLGV34COm1.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=BB9F6F7F74BC802DC138DA7AE53346BCFFB83D6A433527815905ACC2D5AA10A1F27FB4250A1FFD27A7EFDAFAE1948F19166023E3C19F78A2&uin=1152921504689611133&src=M500004PKGec38l66T.mp3&redirect=1&fromtag=101982","trackType":""} Apr 29 05:15:55 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:55 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:55 volumio volumio[4503]: info: ------------------------------ 38ms Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:55 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:55 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:58 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:15:58 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:58 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:15:58 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:15:58 volumio volumio[4503]: info: Apr 29 05:15:58 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:58 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:58 volumio volumio[4503]: info: Apr 29 05:15:58 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:58 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:58 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:58 volumio volumio[4503]: info: sendMpdCommand stop took 39 milliseconds Apr 29 05:15:58 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:15:58 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:15:58 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:15:58 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:15:58 volumio volumio[4503]: info: sendMpdCommand status took 3 milliseconds Apr 29 05:15:58 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:58 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:15:58 volumio volumio[4503]: 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 29 05:15:58 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:58 volumio volumio[4503]: info: No code Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:58 volumio volumio[4503]: info: ------------------------------ 18ms Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:58 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:15:58 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:15:58 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:15:59 volumio volumio[4503]: info: Apr 29 05:15:59 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:59 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:59 volumio volumio[4503]: info: Apr 29 05:15:59 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:59 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:59 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:59 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:59 volumio volumio[4503]: info: Apr 29 05:15:59 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:59 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:59 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:59 volumio volumio[4503]: info: Apr 29 05:15:59 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:59 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:59 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:59 volumio volumio[4503]: info: Apr 29 05:15:59 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:59 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:59 volumio volumio[4503]: info: ------------------------------ 1ms Apr 29 05:15:59 volumio volumio[4503]: info: Apr 29 05:15:59 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:59 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:59 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:15:59 volumio volumio[4503]: info: Apr 29 05:15:59 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:59 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:15:59 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:59 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:59 volumio volumio[4503]: info: Apr 29 05:15:59 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:15:59 volumio volumio[4503]: info: sendMpdCommand status took 14 milliseconds Apr 29 05:15:59 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:15:59 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:15:59 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:59 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:59 volumio volumio[4503]: info: Apr 29 05:15:59 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:15:59 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:15:59 volumio volumio[4503]: info: sendMpdCommand status took 3 milliseconds Apr 29 05:15:59 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 05:15:59 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:15:59 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:15:59 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:59 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:59 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:59 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liebesfreud","artist":"Joshua Bell","album":"Essential Violin","uri":"http://isure6.stream.qqmusic.qq.com/M500002KFtLy1Redug.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=A9B4D6BB419F166E83D582D26EF6845ABC983E466D377F6B0012DF1C14DBD10660565389C6FE8FE2C2693688416E78FE3172B15D18D34B02&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:59 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:59 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:59 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:15:59 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:59 volumio volumio[4503]: info: ------------------------------ 28ms Apr 29 05:15:59 volumio volumio[4503]: info: ------------------------------ 13ms Apr 29 05:15:59 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 29 05:15:59 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:15:59 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:15:59 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:15:59 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Liebesfreud","artist":"Joshua Bell","album":"Essential Violin","uri":"http://isure6.stream.qqmusic.qq.com/M500002KFtLy1Redug.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=A9B4D6BB419F166E83D582D26EF6845ABC983E466D377F6B0012DF1C14DBD10660565389C6FE8FE2C2693688416E78FE3172B15D18D34B02&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:15:59 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:15:59 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:15:59 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:15:59 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:15:59 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:59 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:15:59 volumio volumio[4503]: info: ------------------------------ 32ms Apr 29 05:15:59 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:00 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:02 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:09 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:09 volumio systemd-journal[206]: Suppressed 569 messages from /system.slice/volumio.service Apr 29 05:16:09 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:16:09 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:16:09 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:16:09 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:16:09 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:16:09 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:16:09 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:16:09 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:16:09 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:09 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:09 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:09 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:09 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:16:09 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:09 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:16:09 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:16:09 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:16:09 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:16:09 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:09 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:09 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:16:10 volumio volumio[4503]: info: sendMpdCommand stop took 91 milliseconds Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:10 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:10 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:10 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:10 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:10 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:10 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:10 volumio volumio[4503]: 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 29 05:16:10 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:10 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:10 volumio volumio[4503]: info: No code Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:10 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 17ms Apr 29 05:16:10 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:16:10 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:16:10 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:16:10 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 18ms Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:10 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:10 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:10 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:10 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:10 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:10 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:16:10 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:10 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:10 volumio volumio[4503]: info: sendMpdCommand status took 7 milliseconds Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:10 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:10 volumio volumio[4503]: info: Apr 29 05:16:10 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:10 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:10 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:10 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:10 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:10 volumio volumio[4503]: 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":"The Carnival of the Animals - The Swan","artist":"Jacqueline Du Pre","album":"Jacqueline du Pré: The Complete EMI Recordings","uri":"http://isure6.stream.qqmusic.qq.com/M500003Tl3NL2AtO7u.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=D0D12412BB0A37EF4C0CBC26408603A2C9C3034E3CBB29F11B895A65B11D9D8065C58B5E134453DB295B8E4449004F60EE3BF0BDCE62F20D&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:10 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 34ms Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 19ms Apr 29 05:16:10 volumio volumio[4503]: info: sendMpdCommand status took 18 milliseconds Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:10 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:10 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 29 05:16:10 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:10 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:10 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:10 volumio volumio[4503]: 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":"The Carnival of the Animals - The Swan","artist":"Jacqueline Du Pre","album":"Jacqueline du Pré: The Complete EMI Recordings","uri":"http://isure6.stream.qqmusic.qq.com/M500003Tl3NL2AtO7u.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=D0D12412BB0A37EF4C0CBC26408603A2C9C3034E3CBB29F11B895A65B11D9D8065C58B5E134453DB295B8E4449004F60EE3BF0BDCE62F20D&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:10 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:16:10 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:10 volumio volumio[4503]: info: ------------------------------ 54ms Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:10 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:10 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:10 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:10 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:11 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:16:12 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:12 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:12 volumio volumio[4503]: info: sendMpdCommand stop took 37 milliseconds Apr 29 05:16:12 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:12 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:12 volumio volumio[4503]: info: sendMpdCommand status took 4 milliseconds Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:12 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:12 volumio volumio[4503]: 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 29 05:16:12 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:12 volumio volumio[4503]: info: No code Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 63ms Apr 29 05:16:12 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 63ms Apr 29 05:16:12 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:16:12 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:12 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:12 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:12 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:12 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:12 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:12 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:12 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:12 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:12 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:12 volumio volumio[4503]: info: Apr 29 05:16:12 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:12 volumio volumio[4503]: info: sendMpdCommand status took 15 milliseconds Apr 29 05:16:12 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 7ms Apr 29 05:16:12 volumio volumio[4503]: info: sendMpdCommand status took 5 milliseconds Apr 29 05:16:12 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:12 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:12 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:12 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Million Years Ago (Violin Cover)","artist":"Bryson Andres","album":null,"uri":"http://isure6.stream.qqmusic.qq.com/M50000457Ok90aJozY.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=141CAA1180257CF7646A574831BF3163C770E42158EA821CE2771055D05904332CFDA6D6C72C1CE088ED6FB2C4B6726EBCC43AA0A121C182&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:12 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 41ms Apr 29 05:16:12 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 29 05:16:12 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:12 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:12 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:12 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Million Years Ago (Violin Cover)","artist":"Bryson Andres","album":null,"uri":"http://isure6.stream.qqmusic.qq.com/M50000457Ok90aJozY.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=141CAA1180257CF7646A574831BF3163C770E42158EA821CE2771055D05904332CFDA6D6C72C1CE088ED6FB2C4B6726EBCC43AA0A121C182&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:12 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:16:12 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:12 volumio volumio[4503]: info: ------------------------------ 49ms Apr 29 05:16:12 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:12 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:12 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:14 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:16:14 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:14 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:16:14 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:16:14 volumio volumio[4503]: info: Apr 29 05:16:14 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:14 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:14 volumio volumio[4503]: info: Apr 29 05:16:14 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:14 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:14 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:14 volumio volumio[4503]: info: sendMpdCommand stop took 18 milliseconds Apr 29 05:16:14 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:16:14 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:16:14 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:16:14 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:14 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:14 volumio volumio[4503]: 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 29 05:16:14 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:14 volumio volumio[4503]: info: No code Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:14 volumio volumio[4503]: info: ------------------------------ 24ms Apr 29 05:16:14 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:16:14 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:16:14 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:16:14 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:14 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:16:15 volumio volumio[4503]: info: Apr 29 05:16:15 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:15 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:15 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:15 volumio volumio[4503]: info: Apr 29 05:16:15 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:15 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:15 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:15 volumio volumio[4503]: info: Apr 29 05:16:15 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:15 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:15 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:15 volumio volumio[4503]: info: Apr 29 05:16:15 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:15 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:15 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:15 volumio volumio[4503]: info: Apr 29 05:16:15 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:15 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:15 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:15 volumio volumio[4503]: info: Apr 29 05:16:15 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:15 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:15 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:15 volumio volumio[4503]: info: Apr 29 05:16:15 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:15 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:15 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:15 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:15 volumio volumio[4503]: info: Apr 29 05:16:15 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:15 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:15 volumio volumio[4503]: info: Apr 29 05:16:15 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:15 volumio volumio[4503]: info: sendMpdCommand status took 15 milliseconds Apr 29 05:16:15 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:15 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:15 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:15 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:15 volumio volumio[4503]: info: ------------------------------ 6ms Apr 29 05:16:15 volumio volumio[4503]: info: sendMpdCommand status took 3 milliseconds Apr 29 05:16:15 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 29 05:16:15 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:15 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:15 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:15 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:15 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:15 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"晴朗的一天","artist":"Mirella Freni","album":"Ten Top of Opera Hits","uri":"http://isure6.stream.qqmusic.qq.com/M500003VBR1l2Asx8a.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=1D6CBF908513D643A61B83A72FDE226CE35710155B9EFBE33816300806CBDCC9A941B9081CA8557426AAEC95FF6214A058E3BF0A5112396F&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:15 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:15 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:15 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:15 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:15 volumio volumio[4503]: info: ------------------------------ 34ms Apr 29 05:16:15 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 29 05:16:15 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:15 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:15 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:15 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"晴朗的一天","artist":"Mirella Freni","album":"Ten Top of Opera Hits","uri":"http://isure6.stream.qqmusic.qq.com/M500003VBR1l2Asx8a.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=1D6CBF908513D643A61B83A72FDE226CE35710155B9EFBE33816300806CBDCC9A941B9081CA8557426AAEC95FF6214A058E3BF0A5112396F&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:15 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:15 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:15 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:16:15 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:16:15 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:15 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:15 volumio volumio[4503]: info: ------------------------------ 35ms Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:15 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:15 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:15 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:15 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:15 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:17 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:16:17 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:17 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:16:17 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:16:17 volumio volumio[4503]: info: Apr 29 05:16:17 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:17 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:17 volumio volumio[4503]: info: Apr 29 05:16:17 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:17 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:17 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:17 volumio volumio[4503]: info: sendMpdCommand stop took 84 milliseconds Apr 29 05:16:17 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:16:17 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:16:17 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:16:17 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:16:17 volumio volumio[4503]: info: sendMpdCommand status took 3 milliseconds Apr 29 05:16:17 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:17 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:17 volumio volumio[4503]: 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 29 05:16:17 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:17 volumio volumio[4503]: info: No code Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:17 volumio volumio[4503]: info: ------------------------------ 23ms Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:17 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:17 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:17 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:18 volumio volumio[4503]: info: Apr 29 05:16:18 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:18 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:18 volumio volumio[4503]: info: Apr 29 05:16:18 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:18 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:18 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:16:18 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:18 volumio volumio[4503]: info: Apr 29 05:16:18 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:18 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:18 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:18 volumio volumio[4503]: info: Apr 29 05:16:18 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:18 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:18 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:18 volumio volumio[4503]: info: Apr 29 05:16:18 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:18 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:18 volumio volumio[4503]: info: Apr 29 05:16:18 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:18 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:18 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:18 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:18 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:16:18 volumio volumio[4503]: info: Apr 29 05:16:18 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:18 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:18 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:18 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:18 volumio volumio[4503]: info: sendMpdCommand status took 14 milliseconds Apr 29 05:16:18 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:18 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:18 volumio volumio[4503]: info: Apr 29 05:16:18 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:18 volumio volumio[4503]: info: Apr 29 05:16:18 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:18 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:18 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:18 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:18 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 05:16:18 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:18 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:18 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:18 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:18 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":512,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lakmé, Act II: \"Ah ! Par les dieux inspirés...Où va la jeune hindoue...\" (Lakmé)","artist":"Natalie Dessay","album":"Le miracle d'une voix","uri":"http://isure6.stream.qqmusic.qq.com/M500001fqJhU1V2R1v.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=761BC062DE1957BF0F1DD46E1B2F19F0E7E105DDBDEC3FD0C1BB3824B7CB2BF454934E82EAF1C93D083D88A3E3A01E72A8D88C17B16088D2&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:18 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 27ms Apr 29 05:16:19 volumio volumio[4503]: info: sendMpdCommand status took 12 milliseconds Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 13ms Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:19 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:19 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:19 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":512,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lakmé, Act II: \"Ah ! Par les dieux inspirés...Où va la jeune hindoue...\" (Lakmé)","artist":"Natalie Dessay","album":"Le miracle d'une voix","uri":"http://isure6.stream.qqmusic.qq.com/M500001fqJhU1V2R1v.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=761BC062DE1957BF0F1DD46E1B2F19F0E7E105DDBDEC3FD0C1BB3824B7CB2BF454934E82EAF1C93D083D88A3E3A01E72A8D88C17B16088D2&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:19 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:16:19 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 34ms Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:16:19 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:19 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:19 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:19 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:19 volumio volumio[4503]: info: sendMpdCommand stop took 44 milliseconds Apr 29 05:16:19 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:16:19 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:16:19 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:16:19 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:19 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:19 volumio volumio[4503]: 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 29 05:16:19 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:19 volumio volumio[4503]: info: No code Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 19ms Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:19 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:19 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:19 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:19 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:19 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:19 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:19 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:19 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:19 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:19 volumio volumio[4503]: info: sendMpdCommand status took 10 milliseconds Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:19 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:19 volumio volumio[4503]: info: Apr 29 05:16:19 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:19 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:19 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 05:16:19 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:19 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:19 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:19 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:19 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":68,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"电影原声diva女高音","artist":"第五元素","album":"歌曲合辑","uri":"http://isure6.stream.qqmusic.qq.com/M500003ex1V80vhcgv.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=907CBC3A9DFDC69E0FE8732048975AE00BA0B2F80F431033E7E2D8F0DE8185F41678AD7EEABFD84A45D933CAF2362AC92ED63E78B269DA35&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:19 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:19 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:20 volumio volumio[4503]: info: ------------------------------ 33ms Apr 29 05:16:20 volumio volumio[4503]: info: ------------------------------ 20ms Apr 29 05:16:20 volumio volumio[4503]: info: sendMpdCommand status took 19 milliseconds Apr 29 05:16:20 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:20 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 29 05:16:20 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:20 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:20 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:20 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":68,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"电影原声diva女高音","artist":"第五元素","album":"歌曲合辑","uri":"http://isure6.stream.qqmusic.qq.com/M500003ex1V80vhcgv.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=907CBC3A9DFDC69E0FE8732048975AE00BA0B2F80F431033E7E2D8F0DE8185F41678AD7EEABFD84A45D933CAF2362AC92ED63E78B269DA35&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:20 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:16:20 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:20 volumio volumio[4503]: info: ------------------------------ 44ms Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:16:20 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:20 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:16:20 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:16:20 volumio volumio[4503]: info: Apr 29 05:16:20 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:20 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:20 volumio volumio[4503]: info: Apr 29 05:16:20 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:20 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:20 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:20 volumio volumio[4503]: info: sendMpdCommand stop took 107 milliseconds Apr 29 05:16:20 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:16:20 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:16:20 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:16:20 volumio volumio[4503]: info: ------------------------------ 10ms Apr 29 05:16:20 volumio volumio[4503]: info: sendMpdCommand status took 9 milliseconds Apr 29 05:16:20 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:20 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:20 volumio volumio[4503]: 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 29 05:16:20 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:20 volumio volumio[4503]: info: No code Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:20 volumio volumio[4503]: info: ------------------------------ 40ms Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:20 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:20 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:21 volumio volumio[4503]: info: Apr 29 05:16:21 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:21 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:21 volumio volumio[4503]: info: Apr 29 05:16:21 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:21 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:21 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:16:21 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:16:21 volumio volumio[4503]: info: Apr 29 05:16:21 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:21 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:21 volumio volumio[4503]: info: Apr 29 05:16:21 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:21 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:21 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:16:21 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:16:21 volumio volumio[4503]: info: Apr 29 05:16:21 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:21 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:21 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:21 volumio volumio[4503]: info: Apr 29 05:16:21 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:21 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:21 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:21 volumio volumio[4503]: info: Apr 29 05:16:21 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:21 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:21 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:21 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:21 volumio volumio[4503]: info: sendMpdCommand status took 14 milliseconds Apr 29 05:16:21 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:21 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:21 volumio volumio[4503]: info: Apr 29 05:16:21 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:21 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:21 volumio volumio[4503]: info: Apr 29 05:16:21 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:21 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:21 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:21 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 29 05:16:21 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:21 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:21 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:21 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"亲爱的父亲","artist":"Renata Tebaldi","album":"惠威试音天碟Ⅴ","uri":"http://isure6.stream.qqmusic.qq.com/M500002XwsE534VW3Z.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=59A9152483640CB9C5F0C075F26B23435559D4D7C33CDA9248D79DEAB66163F000799D835A6CFFC9A37D5D63FD8A3FF73EC852309ABA0C1A&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:21 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:21 volumio volumio[4503]: info: ------------------------------ 40ms Apr 29 05:16:21 volumio volumio[4503]: info: ------------------------------ 21ms Apr 29 05:16:21 volumio volumio[4503]: info: sendMpdCommand status took 20 milliseconds Apr 29 05:16:21 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:21 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:21 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:21 volumio volumio[4503]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 29 05:16:21 volumio volumio[4503]: verbose: ControllerMpd::parseTrackInfo Apr 29 05:16:21 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:21 volumio volumio[4503]: verbose: In UPNP mode Apr 29 05:16:21 volumio volumio[4503]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"亲爱的父亲","artist":"Renata Tebaldi","album":"惠威试音天碟Ⅴ","uri":"http://isure6.stream.qqmusic.qq.com/M500002XwsE534VW3Z.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=59A9152483640CB9C5F0C075F26B23435559D4D7C33CDA9248D79DEAB66163F000799D835A6CFFC9A37D5D63FD8A3FF73EC852309ABA0C1A&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""} Apr 29 05:16:21 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::syncState stateService play Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus play Apr 29 05:16:21 volumio volumio[4503]: info: Received an update from plugin. extracting info from payload Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:21 volumio volumio[4503]: info: ------------------------------ 53ms Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:21 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:21 volumio volumio[4503]: info: CoreCommandRouter::volumioGetQueue Apr 29 05:16:21 volumio volumio[4503]: info: CoreStateMachine::getQueue Apr 29 05:16:21 volumio volumio[4503]: info: CorePlayQueue::getQueue Apr 29 05:16:23 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:16:23 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::stPlaybackTimer Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::updateTrackBlock Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::getTrackBlock Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::serviceStop Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:23 volumio volumio[4503]: info: ControllerMpd::stop Apr 29 05:16:23 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:16:23 volumio volumio[4503]: info: sendMpdCommand stop took 63 milliseconds Apr 29 05:16:23 volumio volumio[4503]: info: Apr 29 05:16:23 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:23 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:23 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:23 volumio volumio[4503]: info: Apr 29 05:16:23 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:23 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:23 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:16:23 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:23 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:23 volumio volumio[4503]: 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 29 05:16:23 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:23 volumio volumio[4503]: info: No code Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:23 volumio volumio[4503]: info: ------------------------------ 18ms Apr 29 05:16:23 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:16:23 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:16:23 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:16:23 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:23 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:16:23 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:16:23 volumio volumio[4503]: info: ------------------------------ 20ms Apr 29 05:16:24 volumio volumio[4503]: info: Apr 29 05:16:24 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:24 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:24 volumio volumio[4503]: info: Apr 29 05:16:24 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:24 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:24 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:24 volumio volumio[4503]: info: ------------------------------ 1ms Apr 29 05:16:24 volumio volumio[4503]: info: Apr 29 05:16:24 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:24 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:24 volumio volumio[4503]: info: Apr 29 05:16:24 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:24 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:24 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:24 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:24 volumio volumio[4503]: info: Apr 29 05:16:24 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:24 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:24 volumio volumio[4503]: info: Apr 29 05:16:24 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:24 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:24 volumio volumio[4503]: info: ------------------------------ 3ms Apr 29 05:16:24 volumio volumio[4503]: info: ------------------------------ 8ms Apr 29 05:16:24 volumio volumio[4503]: info: Clearing queue after UPNP request Apr 29 05:16:24 volumio volumio[4503]: info: CoreStateMachine::ClearQueue Apr 29 05:16:24 volumio volumio[4503]: info: CoreStateMachine::stop Apr 29 05:16:24 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 05:16:24 volumio volumio[4503]: verbose: UNSET VOLATILE Apr 29 05:16:24 volumio volumio[4503]: info: CorePlayQueue::clearPlayQueue Apr 29 05:16:24 volumio volumio[4503]: info: CorePlayQueue::saveQueue Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::volumioPushQueue Apr 29 05:16:24 volumio volumio[4503]: info: Apr 29 05:16:24 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:24 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:24 volumio volumio[4503]: info: Apr 29 05:16:24 volumio volumio[4503]: ---------------------------- MPD announces state update: player Apr 29 05:16:24 volumio volumio[4503]: info: ControllerMpd::getState Apr 29 05:16:24 volumio volumio[4503]: verbose: ControllerMpd::sendMpdCommand status Apr 29 05:16:24 volumio volumio[4503]: error: updateQueue error: null Apr 29 05:16:24 volumio volumio[4503]: info: ------------------------------ 4ms Apr 29 05:16:24 volumio volumio[4503]: info: sendMpdCommand status took 2 milliseconds Apr 29 05:16:24 volumio volumio[4503]: verbose: ControllerMpd::parseState Apr 29 05:16:24 volumio volumio[4503]: info: ControllerMpd::pushState Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::servicePushState Apr 29 05:16:24 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:24 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:24 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:24 volumio volumio[4503]: 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 29 05:16:24 volumio volumio[4503]: verbose: CURRENT POSITION 0 Apr 29 05:16:24 volumio volumio[4503]: info: CoreStateMachine::syncState stateService stop Apr 29 05:16:24 volumio volumio[4503]: info: CoreStateMachine::syncState currentStatus stop Apr 29 05:16:24 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:24 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:24 volumio volumio[4503]: info: No code Apr 29 05:16:24 volumio volumio[4503]: info: CoreStateMachine::pushState Apr 29 05:16:24 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::volumioPushState Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 05:16:24 volumio volumio[4503]: info: ------------------------------ 17ms Apr 29 05:16:24 volumio volumio[4503]: info: Starting UPNP Playback Apr 29 05:16:24 volumio volumio[4503]: info: Preparing playback through UPNP Apr 29 05:16:24 volumio volumio[4503]: info: CoreCommandRouter::volumioGetState Apr 29 05:16:24 volumio volumio[4503]: info: CorePlayQueue::getTrack 0 Apr 29 05:16:24 volumio volumio[4503]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 05:16:25 volumio volumio[4503]: info: Apr 29 05:16:25 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:25 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:25 volumio volumio[4503]: info: Apr 29 05:16:25 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:25 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:25 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:25 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:25 volumio volumio[4503]: info: Apr 29 05:16:25 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:25 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:25 volumio volumio[4503]: info: Apr 29 05:16:25 volumio volumio[4503]: ---------------------------- MPD announces system playlist update Apr 29 05:16:25 volumio volumio[4503]: info: Ignoring MPD Status Update Apr 29 05:16:25 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:25 volumio volumio[4503]: info: ------------------------------ 2ms Apr 29 05:16:25 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 29 05:16:48 volumio systemd-journal[206]: Suppressed 201 messages from /system.slice/volumio.service Apr 29 05:16:48 volumio volumio[4503]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 05:16:48 volumio volumio[4503]: { Error: connect ETIMEDOUT 162.125.2.6:80 Apr 29 05:16:48 volumio volumio[4503]: at Object._errnoException (util.js:1022:11) Apr 29 05:16:48 volumio volumio[4503]: at _exceptionWithHostPort (util.js:1044:20) Apr 29 05:16:48 volumio volumio[4503]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 29 05:16:48 volumio volumio[4503]: code: 'ETIMEDOUT', Apr 29 05:16:48 volumio volumio[4503]: errno: 'ETIMEDOUT', Apr 29 05:16:48 volumio volumio[4503]: syscall: 'connect', Apr 29 05:16:48 volumio volumio[4503]: address: '162.125.2.6', Apr 29 05:16:48 volumio volumio[4503]: port: 80 } Apr 29 05:16:48 volumio volumio[4503]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 05:16:49 volumio sudo[5731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-29 05:15 Apr 29 05:16:49 volumio sudo[5731]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"