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