-- 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"