-- Logs begin at Sun 2024-04-28 02:34:15 UTC, end at Mon 2024-04-29 05:13:01 UTC. --
Apr 29 05:12:24 volumio volumio[894]: info: Clearing queue after UPNP request
Apr 29 05:12:25 volumio volumio[894]: info: CoreStateMachine::ClearQueue
Apr 29 05:12:25 volumio volumio[894]: info: CoreStateMachine::stop
Apr 29 05:12:25 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:12:25 volumio volumio[894]: verbose: UNSET VOLATILE
Apr 29 05:12:25 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:12:25 volumio volumio[894]: info: CorePlayQueue::saveQueue
Apr 29 05:12:25 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:25 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:25 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:12:25 volumio volumio[894]: info:
Apr 29 05:12:25 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:25 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:25 volumio volumio[894]: error: updateQueue error: null
Apr 29 05:12:25 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:25 volumio volumio[894]: info: Starting UPNP Playback
Apr 29 05:12:25 volumio volumio[894]: info: Preparing playback through UPNP
Apr 29 05:12:25 volumio volumio[894]: info: CoreCommandRouter::volumioGetState
Apr 29 05:12:25 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:25 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:12:25 volumio volumio[894]: info:
Apr 29 05:12:25 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:25 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:25 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:25 volumio volumio[894]: info:
Apr 29 05:12:25 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:25 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:25 volumio volumio[894]: info:
Apr 29 05:12:25 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:25 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:25 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:25 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:25 volumio volumio[894]: info:
Apr 29 05:12:25 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:25 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:25 volumio volumio[894]: info: ------------------------------ 1ms
Apr 29 05:12:25 volumio volumio[894]: info:
Apr 29 05:12:25 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:25 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:25 volumio volumio[894]: info:
Apr 29 05:12:25 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:25 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:25 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:25 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:25 volumio volumio[894]: info: Clearing queue after UPNP request
Apr 29 05:12:26 volumio volumio[894]: info: CoreStateMachine::ClearQueue
Apr 29 05:12:26 volumio volumio[894]: info: CoreStateMachine::stop
Apr 29 05:12:26 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:12:26 volumio volumio[894]: verbose: UNSET VOLATILE
Apr 29 05:12:26 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:12:26 volumio volumio[894]: info: CorePlayQueue::saveQueue
Apr 29 05:12:26 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:26 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:26 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:12:26 volumio volumio[894]: info:
Apr 29 05:12:26 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:26 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:26 volumio volumio[894]: error: updateQueue error: null
Apr 29 05:12:26 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:26 volumio volumio[894]: info: Starting UPNP Playback
Apr 29 05:12:26 volumio volumio[894]: info: Preparing playback through UPNP
Apr 29 05:12:26 volumio volumio[894]: info: CoreCommandRouter::volumioGetState
Apr 29 05:12:26 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:26 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:12:26 volumio volumio[894]: info:
Apr 29 05:12:26 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:26 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:26 volumio volumio[894]: info:
Apr 29 05:12:26 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:26 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:26 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:26 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:26 volumio volumio[894]: info:
Apr 29 05:12:26 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:26 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:26 volumio volumio[894]: info:
Apr 29 05:12:26 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:26 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:26 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:26 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:26 volumio volumio[894]: info:
Apr 29 05:12:26 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:26 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:26 volumio volumio[894]: info:
Apr 29 05:12:26 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:26 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:26 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:26 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:27 volumio volumio[894]: info:
Apr 29 05:12:27 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:27 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:27 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:27 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:12:27 volumio volumio[894]: info:
Apr 29 05:12:27 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:27 volumio volumio[894]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:12:27 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:27 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:27 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:27 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:27 volumio volumio[894]: info:
Apr 29 05:12:27 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:27 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:27 volumio volumio[894]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:12:27 volumio volumio[894]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:12:27 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:27 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:27 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:27 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:27 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:27 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:27 volumio volumio[894]: 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:12:27 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:27 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:27 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:12:27 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:27 volumio volumio[894]: info: ------------------------------ 34ms
Apr 29 05:12:27 volumio volumio[894]: info: sendMpdCommand playlistinfo took 12 milliseconds
Apr 29 05:12:27 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:27 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:27 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:27 volumio volumio[894]: 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:12:27 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:27 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:27 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:27 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:27 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:27 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:27 volumio volumio[894]: info: ------------------------------ 38ms
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:27 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:27 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:27 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:27 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:27 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:39 volumio volumio[2918]: 46
Apr 29 05:12:39 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 29 05:12:39 volumio volumio[2918]: Dload Upload Total Spent Left Speed
Apr 29 05:12:39 volumio volumio[894]: info:
Apr 29 05:12:39 volumio volumio[894]: ---------------------------- Client requests Volume 46
Apr 29 05:12:39 volumio volumio[894]: info: VolumeController::SetAlsaVolume46
Apr 29 05:12:39 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:39 volumio volumio[2918]: [155B blob data]
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:39 volumio volumio[2918]: {"time":1714367559677,"response":"volume Success"}46
Apr 29 05:12:39 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:39 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:39 volumio volumio[894]: info:
Apr 29 05:12:39 volumio volumio[894]: ---------------------------- MPD announces state update: mixer
Apr 29 05:12:39 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:39 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:39 volumio volumio[894]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:12:39 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:39 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:39 volumio volumio[894]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 29 05:12:39 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:39 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:39 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:39 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13084,"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:12:39 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:39 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:39 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:39 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:39 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:39 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:39 volumio volumio[894]: info: ------------------------------ 21ms
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:39 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:39 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:39 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:39 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:39 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio volumio[2918]: 52
Apr 29 05:12:40 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 29 05:12:40 volumio volumio[2918]: Dload Upload Total Spent Left Speed
Apr 29 05:12:40 volumio volumio[894]: info:
Apr 29 05:12:40 volumio volumio[894]: ---------------------------- Client requests Volume 52
Apr 29 05:12:40 volumio volumio[894]: info: VolumeController::SetAlsaVolume52
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:40 volumio volumio[2918]: [155B blob data]
Apr 29 05:12:40 volumio volumio[2918]: {"time":1714367560119,"response":"volume Success"}52
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio volumio[894]: info:
Apr 29 05:12:40 volumio volumio[894]: ---------------------------- MPD announces state update: mixer
Apr 29 05:12:40 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:40 volumio volumio[894]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:40 volumio volumio[2918]: 77
Apr 29 05:12:40 volumio volumio[894]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:40 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:40 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:40 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13479,"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:12:40 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:40 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:40 volumio volumio[894]: info: ------------------------------ 26ms
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 29 05:12:40 volumio volumio[2918]: Dload Upload Total Spent Left Speed
Apr 29 05:12:40 volumio volumio[894]: info:
Apr 29 05:12:40 volumio volumio[894]: ---------------------------- Client requests Volume 77
Apr 29 05:12:40 volumio volumio[894]: info: VolumeController::SetAlsaVolume77
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:40 volumio volumio[2918]: [155B blob data]
Apr 29 05:12:40 volumio volumio[2918]: {"time":1714367560211,"response":"volume Success"}77
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio volumio[894]: info:
Apr 29 05:12:40 volumio volumio[894]: ---------------------------- MPD announces state update: mixer
Apr 29 05:12:40 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:40 volumio volumio[894]: info: sendMpdCommand status took 1 milliseconds
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:40 volumio volumio[894]: info: sendMpdCommand playlistinfo took 5 milliseconds
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:40 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:40 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:40 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13583,"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:12:40 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:40 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:40 volumio volumio[894]: info: ------------------------------ 25ms
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio volumio[2918]: 96
Apr 29 05:12:40 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 29 05:12:40 volumio volumio[2918]: Dload Upload Total Spent Left Speed
Apr 29 05:12:40 volumio volumio[894]: info:
Apr 29 05:12:40 volumio volumio[894]: ---------------------------- Client requests Volume 96
Apr 29 05:12:40 volumio volumio[894]: info: VolumeController::SetAlsaVolume96
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:40 volumio volumio[2918]: [155B blob data]
Apr 29 05:12:40 volumio volumio[2918]: {"time":1714367560401,"response":"volume Success"}96
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio volumio[894]: info:
Apr 29 05:12:40 volumio volumio[894]: ---------------------------- MPD announces state update: mixer
Apr 29 05:12:40 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:40 volumio volumio[894]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:40 volumio volumio[894]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:12:40 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:40 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:40 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:40 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13781,"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:12:40 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:40 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:40 volumio volumio[894]: info: ------------------------------ 38ms
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:40 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:40 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:40 volumio kernel: Under-voltage detected! (0x00050005)
Apr 29 05:12:41 volumio volumio[2918]: 87
Apr 29 05:12:41 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 29 05:12:41 volumio volumio[2918]: Dload Upload Total Spent Left Speed
Apr 29 05:12:41 volumio volumio[894]: info:
Apr 29 05:12:41 volumio volumio[894]: ---------------------------- Client requests Volume 87
Apr 29 05:12:41 volumio volumio[894]: info: VolumeController::SetAlsaVolume87
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:41 volumio volumio[2918]: [155B blob data]
Apr 29 05:12:41 volumio volumio[2918]: {"time":1714367561560,"response":"volume Success"}87
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:41 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:41 volumio volumio[894]: info:
Apr 29 05:12:41 volumio volumio[894]: ---------------------------- MPD announces state update: mixer
Apr 29 05:12:41 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:41 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:41 volumio volumio[894]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:12:41 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:41 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:41 volumio volumio[894]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:12:41 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:41 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:41 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:41 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14988,"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:12:41 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:41 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:41 volumio volumio[894]: info: ------------------------------ 43ms
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:41 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:41 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:41 volumio volumio[2918]: 81
Apr 29 05:12:41 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 29 05:12:41 volumio volumio[2918]: Dload Upload Total Spent Left Speed
Apr 29 05:12:41 volumio volumio[894]: info:
Apr 29 05:12:41 volumio volumio[894]: ---------------------------- Client requests Volume 81
Apr 29 05:12:41 volumio volumio[894]: info: VolumeController::SetAlsaVolume81
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:41 volumio volumio[2918]: [155B blob data]
Apr 29 05:12:41 volumio volumio[2918]: {"time":1714367561810,"response":"volume Success"}81
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:41 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:41 volumio volumio[894]: info:
Apr 29 05:12:41 volumio volumio[894]: ---------------------------- MPD announces state update: mixer
Apr 29 05:12:41 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:41 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:41 volumio volumio[894]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:12:41 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:41 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:41 volumio volumio[894]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:12:41 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:41 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:41 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:41 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15185,"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:12:41 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:41 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:41 volumio volumio[894]: info: ------------------------------ 54ms
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:41 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:41 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:41 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:41 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:43 volumio volumio[2918]: 87
Apr 29 05:12:43 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 29 05:12:43 volumio volumio[2918]: Dload Upload Total Spent Left Speed
Apr 29 05:12:43 volumio volumio[894]: info:
Apr 29 05:12:43 volumio volumio[894]: ---------------------------- Client requests Volume 87
Apr 29 05:12:43 volumio volumio[894]: info: VolumeController::SetAlsaVolume87
Apr 29 05:12:43 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:43 volumio volumio[2918]: [155B blob data]
Apr 29 05:12:43 volumio volumio[2918]: {"time":1714367563477,"response":"volume Success"}87
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:43 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:43 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:43 volumio volumio[894]: info:
Apr 29 05:12:43 volumio volumio[894]: ---------------------------- MPD announces state update: mixer
Apr 29 05:12:43 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:43 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:43 volumio volumio[894]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:12:43 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:43 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:43 volumio volumio[894]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:12:43 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:43 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:43 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:43 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16880,"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:12:43 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:43 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:43 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:43 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:43 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:43 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:43 volumio volumio[894]: info: ------------------------------ 21ms
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:43 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:43 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:43 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:43 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:43 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:46 volumio kernel: Voltage normalised (0x00000000)
Apr 29 05:12:51 volumio volumio[894]: info: Clearing queue after UPNP request
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::ClearQueue
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::stop
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:12:51 volumio volumio[894]: verbose: UNSET VOLATILE
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::getTrackBlock
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::serviceStop
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:51 volumio volumio[894]: info: ControllerMpd::stop
Apr 29 05:12:51 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::saveQueue
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:12:51 volumio volumio[894]: info:
Apr 29 05:12:51 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:51 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:51 volumio volumio[894]: info:
Apr 29 05:12:51 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:51 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:51 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:51 volumio volumio[894]: info: sendMpdCommand stop took 69 milliseconds
Apr 29 05:12:51 volumio volumio[894]: info: Starting UPNP Playback
Apr 29 05:12:51 volumio volumio[894]: info: Preparing playback through UPNP
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioGetState
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:12:51 volumio volumio[894]: error: updateQueue error: null
Apr 29 05:12:51 volumio volumio[894]: info: ------------------------------ 5ms
Apr 29 05:12:51 volumio volumio[894]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:12:51 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:51 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:51 volumio volumio[894]: 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:12:51 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:51 volumio volumio[894]: info: No code
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:51 volumio volumio[894]: info: ------------------------------ 21ms
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:51 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:51 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:51 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:51 volumio volumio[894]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Natalie%20Dessay/Le%20miracle%20d%27une%20voix/c60f0e7d-b1bd-4e32-9da7-b595bb8dd109.jpg'
Apr 29 05:12:52 volumio volumio[894]: info:
Apr 29 05:12:52 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:52 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:52 volumio volumio[894]: info:
Apr 29 05:12:52 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:52 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:52 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:52 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:52 volumio volumio[894]: info:
Apr 29 05:12:52 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:52 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:52 volumio volumio[894]: info: ------------------------------ 1ms
Apr 29 05:12:52 volumio volumio[894]: info:
Apr 29 05:12:52 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:52 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:52 volumio volumio[894]: info: ------------------------------ 1ms
Apr 29 05:12:52 volumio volumio[894]: info:
Apr 29 05:12:52 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:52 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:52 volumio volumio[894]: info:
Apr 29 05:12:52 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:52 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:52 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:52 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:52 volumio volumio[894]: info:
Apr 29 05:12:52 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:52 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:12:52 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:52 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:52 volumio volumio[894]: info:
Apr 29 05:12:52 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:52 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:52 volumio volumio[894]: info:
Apr 29 05:12:52 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:52 volumio volumio[894]: info: sendMpdCommand status took 19 milliseconds
Apr 29 05:12:52 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:52 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:52 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:52 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:52 volumio volumio[894]: info: ------------------------------ 5ms
Apr 29 05:12:52 volumio volumio[894]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:12:52 volumio volumio[894]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:12:52 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:52 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:52 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:52 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:52 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:52 volumio volumio[894]: 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:12:52 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:52 volumio volumio[894]: info: ------------------------------ 41ms
Apr 29 05:12:52 volumio volumio[894]: info: sendMpdCommand playlistinfo took 17 milliseconds
Apr 29 05:12:52 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:52 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:52 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:52 volumio volumio[894]: 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:12:52 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:52 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:52 volumio volumio[894]: info: ------------------------------ 41ms
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:52 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:52 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:52 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:52 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:52 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:53 volumio volumio[894]: info: Clearing queue after UPNP request
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::ClearQueue
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::stop
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:12:53 volumio volumio[894]: verbose: UNSET VOLATILE
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::getTrackBlock
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::serviceStop
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:53 volumio volumio[894]: info: ControllerMpd::stop
Apr 29 05:12:53 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::saveQueue
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:12:53 volumio volumio[894]: info:
Apr 29 05:12:53 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:53 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:53 volumio volumio[894]: info:
Apr 29 05:12:53 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:53 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:53 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:53 volumio volumio[894]: info: sendMpdCommand stop took 16 milliseconds
Apr 29 05:12:53 volumio volumio[894]: error: updateQueue error: null
Apr 29 05:12:53 volumio volumio[894]: info: ------------------------------ 4ms
Apr 29 05:12:53 volumio volumio[894]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:12:53 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:53 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:53 volumio volumio[894]: 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:12:53 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:53 volumio volumio[894]: info: No code
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:53 volumio volumio[894]: info: ------------------------------ 19ms
Apr 29 05:12:53 volumio volumio[894]: info: Starting UPNP Playback
Apr 29 05:12:53 volumio volumio[894]: info: Preparing playback through UPNP
Apr 29 05:12:53 volumio volumio[894]: info: CoreCommandRouter::volumioGetState
Apr 29 05:12:53 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:53 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:12:54 volumio volumio[894]: info:
Apr 29 05:12:54 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:54 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:54 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:54 volumio volumio[894]: info:
Apr 29 05:12:54 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:54 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:54 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:54 volumio volumio[894]: info:
Apr 29 05:12:54 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:54 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:54 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:54 volumio volumio[894]: info:
Apr 29 05:12:54 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:54 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:54 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:54 volumio volumio[894]: info:
Apr 29 05:12:54 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:54 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:54 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:54 volumio volumio[894]: info:
Apr 29 05:12:54 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:54 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:54 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:54 volumio volumio[894]: info:
Apr 29 05:12:54 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:54 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:54 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:54 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:12:54 volumio volumio[894]: info:
Apr 29 05:12:54 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:54 volumio volumio[894]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:12:54 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:54 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:54 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:54 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:54 volumio volumio[894]: info:
Apr 29 05:12:54 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:54 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:54 volumio volumio[894]: info: sendMpdCommand status took 5 milliseconds
Apr 29 05:12:54 volumio volumio[894]: info: sendMpdCommand playlistinfo took 5 milliseconds
Apr 29 05:12:54 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:54 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:54 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:54 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:54 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:54 volumio volumio[894]: 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":null,"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:12:54 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:54 volumio volumio[894]: info: ------------------------------ 34ms
Apr 29 05:12:54 volumio volumio[894]: info: ------------------------------ 15ms
Apr 29 05:12:54 volumio volumio[894]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 29 05:12:54 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:54 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:54 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:54 volumio volumio[894]: 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:12:54 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:54 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:54 volumio volumio[894]: info: ------------------------------ 38ms
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:54 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:54 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:54 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:54 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:54 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:55 volumio volumio[894]: info: Clearing queue after UPNP request
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::ClearQueue
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::stop
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:12:55 volumio volumio[894]: verbose: UNSET VOLATILE
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::getTrackBlock
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::serviceStop
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:55 volumio volumio[894]: info: ControllerMpd::stop
Apr 29 05:12:55 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::saveQueue
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:12:55 volumio volumio[894]: info:
Apr 29 05:12:55 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:55 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:55 volumio volumio[894]: info:
Apr 29 05:12:55 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:55 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:55 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:55 volumio volumio[894]: info: sendMpdCommand stop took 65 milliseconds
Apr 29 05:12:55 volumio volumio[894]: error: updateQueue error: null
Apr 29 05:12:55 volumio volumio[894]: info: ------------------------------ 5ms
Apr 29 05:12:55 volumio volumio[894]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:12:55 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:55 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:55 volumio volumio[894]: 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:12:55 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:55 volumio volumio[894]: info: No code
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:55 volumio volumio[894]: info: ------------------------------ 27ms
Apr 29 05:12:55 volumio volumio[894]: info: Starting UPNP Playback
Apr 29 05:12:55 volumio volumio[894]: info: Preparing playback through UPNP
Apr 29 05:12:55 volumio volumio[894]: info: CoreCommandRouter::volumioGetState
Apr 29 05:12:55 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:55 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:12:56 volumio volumio[894]: info:
Apr 29 05:12:56 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:56 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:56 volumio volumio[894]: info: ------------------------------ 1ms
Apr 29 05:12:56 volumio volumio[894]: info:
Apr 29 05:12:56 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:56 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:56 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:56 volumio volumio[894]: info:
Apr 29 05:12:56 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:56 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:56 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:56 volumio volumio[894]: info:
Apr 29 05:12:56 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:56 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:56 volumio volumio[894]: info: ------------------------------ 1ms
Apr 29 05:12:56 volumio volumio[894]: info:
Apr 29 05:12:56 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:56 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:56 volumio volumio[894]: info:
Apr 29 05:12:56 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:56 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:56 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:56 volumio volumio[894]: info: ------------------------------ 2ms
Apr 29 05:12:56 volumio volumio[894]: info:
Apr 29 05:12:56 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:56 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:56 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:56 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:12:56 volumio volumio[894]: info:
Apr 29 05:12:56 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:56 volumio volumio[894]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:12:56 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:56 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:56 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:56 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:56 volumio volumio[894]: info:
Apr 29 05:12:56 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:56 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:56 volumio volumio[894]: info: sendMpdCommand status took 16 milliseconds
Apr 29 05:12:56 volumio volumio[894]: info: sendMpdCommand playlistinfo took 16 milliseconds
Apr 29 05:12:56 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:56 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:56 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:56 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:56 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:56 volumio volumio[894]: 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:12:56 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:56 volumio volumio[894]: info: ------------------------------ 50ms
Apr 29 05:12:56 volumio volumio[894]: info: ------------------------------ 20ms
Apr 29 05:12:56 volumio volumio[894]: info: sendMpdCommand playlistinfo took 16 milliseconds
Apr 29 05:12:56 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:56 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:56 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:56 volumio volumio[894]: 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:12:56 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:56 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:56 volumio volumio[894]: info: ------------------------------ 53ms
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:56 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:56 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:56 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:56 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:56 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:57 volumio kernel: Under-voltage detected! (0x00050005)
Apr 29 05:12:57 volumio volumio[894]: info: Clearing queue after UPNP request
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::ClearQueue
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::stop
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:12:57 volumio volumio[894]: verbose: UNSET VOLATILE
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::getTrackBlock
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::serviceStop
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:57 volumio volumio[894]: info: ControllerMpd::stop
Apr 29 05:12:57 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::saveQueue
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:12:57 volumio volumio[894]: info:
Apr 29 05:12:57 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:57 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:57 volumio volumio[894]: info:
Apr 29 05:12:57 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:57 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:57 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:57 volumio volumio[894]: info: sendMpdCommand stop took 32 milliseconds
Apr 29 05:12:57 volumio volumio[894]: error: updateQueue error: null
Apr 29 05:12:57 volumio volumio[894]: info: ------------------------------ 6ms
Apr 29 05:12:57 volumio volumio[894]: info: sendMpdCommand status took 5 milliseconds
Apr 29 05:12:57 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:57 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:57 volumio volumio[894]: 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:12:57 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:57 volumio volumio[894]: info: No code
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:57 volumio volumio[894]: info: ------------------------------ 34ms
Apr 29 05:12:57 volumio volumio[894]: info: Starting UPNP Playback
Apr 29 05:12:57 volumio volumio[894]: info: Preparing playback through UPNP
Apr 29 05:12:57 volumio volumio[894]: info: CoreCommandRouter::volumioGetState
Apr 29 05:12:57 volumio volumio[894]: info: CorePlayQueue::getTrack 0
Apr 29 05:12:57 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:12:58 volumio volumio[894]: info:
Apr 29 05:12:58 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:58 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:58 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:58 volumio volumio[894]: info:
Apr 29 05:12:58 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:58 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:58 volumio volumio[894]: info:
Apr 29 05:12:58 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:58 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:58 volumio volumio[894]: info: ------------------------------ 4ms
Apr 29 05:12:58 volumio volumio[894]: info: ------------------------------ 4ms
Apr 29 05:12:58 volumio volumio[894]: info:
Apr 29 05:12:58 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:58 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:58 volumio volumio[894]: info:
Apr 29 05:12:58 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:58 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:58 volumio volumio[894]: info: ------------------------------ 4ms
Apr 29 05:12:58 volumio volumio[894]: info: ------------------------------ 3ms
Apr 29 05:12:58 volumio volumio[894]: info:
Apr 29 05:12:58 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:58 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:58 volumio volumio[894]: info: ------------------------------ 4ms
Apr 29 05:12:58 volumio volumio[894]: info:
Apr 29 05:12:58 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:58 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:58 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:58 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:12:58 volumio volumio[894]: info: sendMpdCommand status took 20 milliseconds
Apr 29 05:12:58 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:58 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:58 volumio volumio[894]: info:
Apr 29 05:12:58 volumio volumio[894]: ---------------------------- MPD announces system playlist update
Apr 29 05:12:58 volumio volumio[894]: info: Ignoring MPD Status Update
Apr 29 05:12:58 volumio volumio[894]: info:
Apr 29 05:12:58 volumio volumio[894]: ---------------------------- MPD announces state update: player
Apr 29 05:12:58 volumio volumio[894]: info: ControllerMpd::getState
Apr 29 05:12:58 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:12:58 volumio volumio[894]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:12:58 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:58 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:58 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:58 volumio volumio[894]: 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":"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:12:58 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:58 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:58 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:12:58 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:58 volumio volumio[894]: info: ------------------------------ 52ms
Apr 29 05:12:58 volumio volumio[894]: info: ------------------------------ 29ms
Apr 29 05:12:58 volumio volumio[894]: info: sendMpdCommand status took 47 milliseconds
Apr 29 05:12:58 volumio volumio[894]: verbose: ControllerMpd::parseState
Apr 29 05:12:58 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:12:58 volumio volumio[894]: info: CoreStateMachine::getQueue
Apr 29 05:12:58 volumio volumio[894]: info: CorePlayQueue::getQueue
Apr 29 05:12:58 volumio volumio[894]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 29 05:12:58 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:12:58 volumio volumio[894]: info: ControllerMpd::pushState
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::servicePushState
Apr 29 05:12:58 volumio volumio[894]: verbose: In UPNP mode
Apr 29 05:12:58 volumio volumio[894]: 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:12:58 volumio volumio[894]: verbose: CURRENT POSITION 0
Apr 29 05:12:58 volumio volumio[894]: info: CoreStateMachine::syncState stateService play
Apr 29 05:12:58 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:12:58 volumio volumio[894]: info: Received an update from plugin. extracting info from payload
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:12:58 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:58 volumio volumio[894]: info: CoreStateMachine::pushState
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::volumioPushState
Apr 29 05:12:58 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:12:58 volumio volumio[894]: info: ------------------------------ 91ms
Apr 29 05:13:00 volumio volumio[894]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 29 05:13:00 volumio volumio[894]: { Error: connect ETIMEDOUT 173.234.53.168:443
Apr 29 05:13:00 volumio volumio[894]: at Object._errnoException (util.js:1022:11)
Apr 29 05:13:00 volumio volumio[894]: at _exceptionWithHostPort (util.js:1044:20)
Apr 29 05:13:00 volumio volumio[894]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14)
Apr 29 05:13:00 volumio volumio[894]: code: 'ETIMEDOUT',
Apr 29 05:13:00 volumio volumio[894]: errno: 'ETIMEDOUT',
Apr 29 05:13:00 volumio volumio[894]: syscall: 'connect',
Apr 29 05:13:00 volumio volumio[894]: address: '173.234.53.168',
Apr 29 05:13:00 volumio volumio[894]: port: 443 }
Apr 29 05:13:00 volumio volumio[894]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 29 05:13:01 volumio sudo[4478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-29 05:12
Apr 29 05:13:01 volumio sudo[4478]: 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"