-- Logs begin at Wed 2025-07-02 14:50:03 UTC, end at Wed 2025-07-02 23:10:46 UTC. -- Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:02 volumion1 volumio[8654]: info: Jul 02 23:09:02 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:09:02 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:02 volumion1 volumio[8654]: info: Jul 02 23:09:02 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:09:02 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:02 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:02 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:02 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:02 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:02 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5607,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Human Sarcrifice","artist":"Sweetbox","album":"Sweetbox Complete Best","uri":"USB/MUSIC/15 - Human Sarcrifice.mp3","trackType":"mp3"} Jul 02 23:09:02 volumion1 volumio[8654]: verbose: CURRENT POSITION 4 Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:09:02 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:02 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:02 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5607,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Human Sarcrifice","artist":"Sweetbox","album":"Sweetbox Complete Best","uri":"USB/MUSIC/15 - Human Sarcrifice.mp3","trackType":"mp3"} Jul 02 23:09:02 volumion1 volumio[8654]: verbose: CURRENT POSITION 4 Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:09:02 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:02 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:02 volumion1 volumio[8654]: info: ------------------------------ 119ms Jul 02 23:09:02 volumion1 volumio[8654]: info: ------------------------------ 108ms Jul 02 23:09:05 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 23:09:05 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 23:09:09 volumion1 volumio[8654]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=f0469a89c469dd869cdc9a386182c93a" http://updates.volumio.org:7070/downloader-v1/track-device Jul 02 23:09:09 volumion1 volumio[8654]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 02 23:09:09 volumion1 volumio[8654]: Dload Upload Total Spent Left Speed Jul 02 23:09:09 volumion1 volumio[8654]: [235B blob data] Jul 02 23:09:09 volumion1 volumio[8654]: retrying in 5 seconds, trial 2 Jul 02 23:09:09 volumion1 volumio[8654]: Volumio Calling Home Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:20 volumion1 volumio[8654]: info: Jul 02 23:09:20 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:09:20 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:20 volumion1 volumio[8654]: info: Jul 02 23:09:20 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:09:20 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:20 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:20 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:20 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:20 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23858,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Human Sarcrifice","artist":"Sweetbox","album":"Sweetbox Complete Best","uri":"USB/MUSIC/15 - Human Sarcrifice.mp3","trackType":"mp3"} Jul 02 23:09:20 volumion1 volumio[8654]: verbose: CURRENT POSITION 4 Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:09:20 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:20 volumion1 volumio[8654]: info: ------------------------------ 86ms Jul 02 23:09:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:20 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:20 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23858,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Human Sarcrifice","artist":"Sweetbox","album":"Sweetbox Complete Best","uri":"USB/MUSIC/15 - Human Sarcrifice.mp3","trackType":"mp3"} Jul 02 23:09:20 volumion1 volumio[8654]: verbose: CURRENT POSITION 4 Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:09:20 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:20 volumion1 volumio[8654]: info: ------------------------------ 113ms Jul 02 23:09:20 volumion1 volumio[8654]: info: VolumeController::SetAlsaVolume5 Jul 02 23:09:20 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:21 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:21 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:21 volumion1 volumio[8654]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 02 23:09:21 volumion1 volumio[8654]: info: VolumeController::SetAlsaVolume25 Jul 02 23:09:21 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:21 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:21 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:21 volumion1 volumio[8654]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 02 23:09:22 volumion1 volumio[8654]: info: VolumeController::SetAlsaVolume20 Jul 02 23:09:22 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:22 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:22 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:22 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:22 volumion1 volumio[8654]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 02 23:09:23 volumion1 volumio[8654]: info: VolumeController::SetAlsaVolume0 Jul 02 23:09:23 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:23 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:23 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:23 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:23 volumion1 volumio[8654]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::volumioNext Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::next Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::stop Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 23:09:38 volumion1 volumio[8654]: UNSET VOLATILE Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::updateTrackBlock Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrackBlock Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::serviceStop Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 4 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::serviceStop Jul 02 23:09:38 volumion1 volumio[8654]: info: ControllerMpd::stop Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 23:09:38 volumion1 volumio[8654]: info: Jul 02 23:09:38 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:09:38 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::play index undefined Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::startPlaybackTimer Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIC/05 - The Lazy Song.mp3 Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::updateTrackBlock Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrackBlock Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand clear Jul 02 23:09:38 volumion1 volumio[8654]: info: Jul 02 23:09:38 volumion1 volumio[8654]: ---------------------------- MPD announces system playlist update Jul 02 23:09:38 volumion1 volumio[8654]: info: Ignoring MPD Status Update Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC/05 - The Lazy Song.mp3" Jul 02 23:09:38 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:38 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Human Sarcrifice","artist":"Sweetbox","album":"Sweetbox Complete Best","uri":"USB/MUSIC/15 - Human Sarcrifice.mp3","trackType":"mp3"} Jul 02 23:09:38 volumion1 volumio[8654]: verbose: CURRENT POSITION 5 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService stop Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus stop Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:38 volumion1 volumio[8654]: info: No code Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:38 volumion1 volumio[8654]: info: ------------------------------ 54ms Jul 02 23:09:38 volumion1 volumio[8654]: error: updateQueue error: null Jul 02 23:09:38 volumion1 volumio[8654]: info: Jul 02 23:09:38 volumion1 volumio[8654]: ---------------------------- MPD announces system playlist update Jul 02 23:09:38 volumion1 volumio[8654]: info: Ignoring MPD Status Update Jul 02 23:09:38 volumion1 volumio[8654]: info: ------------------------------ 37ms Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand play Jul 02 23:09:38 volumion1 volumio[8654]: info: Jul 02 23:09:38 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:09:38 volumion1 volumio[8654]: info: ------------------------------ 30ms Jul 02 23:09:38 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:38 volumion1 volumio[8654]: info: Jul 02 23:09:38 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:09:38 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:38 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:38 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"The Lazy Song","artist":"Bruno Mars","album":"Doo - Wops & Hooligans","uri":"USB/MUSIC/05 - The Lazy Song.mp3","trackType":"mp3"} Jul 02 23:09:38 volumion1 volumio[8654]: verbose: CURRENT POSITION 5 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus stop Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:38 volumion1 volumio[8654]: info: ------------------------------ 29ms Jul 02 23:09:38 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:38 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:38 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:38 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"The Lazy Song","artist":"Bruno Mars","album":"Doo - Wops & Hooligans","uri":"USB/MUSIC/05 - The Lazy Song.mp3","trackType":"mp3"} Jul 02 23:09:38 volumion1 volumio[8654]: verbose: CURRENT POSITION 5 Jul 02 23:09:38 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:09:39 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:09:39 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:09:39 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:39 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:39 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:39 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:39 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:39 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:39 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:39 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:39 volumion1 volumio[8654]: info: ------------------------------ 51ms Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPause Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreStateMachine::pause Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreStateMachine::servicePause Jul 02 23:09:40 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreCommandRouter::servicePause Jul 02 23:09:40 volumion1 volumio[8654]: info: ControllerMpd::pause Jul 02 23:09:40 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand pause Jul 02 23:09:40 volumion1 volumio[8654]: info: Jul 02 23:09:40 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:09:40 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:40 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:40 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:40 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:40 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:40 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:40 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:40 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1486,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Lazy Song","artist":"Bruno Mars","album":"Doo - Wops & Hooligans","uri":"USB/MUSIC/05 - The Lazy Song.mp3","trackType":"mp3"} Jul 02 23:09:40 volumion1 volumio[8654]: verbose: CURRENT POSITION 5 Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService pause Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus pause Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:40 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:40 volumion1 volumio[8654]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:09:40 volumion1 volumio[8654]: info: ------------------------------ 27ms Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:47 volumion1 volumio[8654]: info: Jul 02 23:09:47 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:09:47 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:47 volumion1 volumio[8654]: info: Jul 02 23:09:47 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:09:47 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:47 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:47 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:47 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:47 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:47 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1486,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Lazy Song","artist":"Bruno Mars","album":"Doo - Wops & Hooligans","uri":"USB/MUSIC/05 - The Lazy Song.mp3","trackType":"mp3"} Jul 02 23:09:47 volumion1 volumio[8654]: verbose: CURRENT POSITION 5 Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService pause Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus pause Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:47 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:09:47 volumion1 volumio[8654]: info: ------------------------------ 82ms Jul 02 23:09:47 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:09:47 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:09:47 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:47 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1486,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Lazy Song","artist":"Bruno Mars","album":"Doo - Wops & Hooligans","uri":"USB/MUSIC/05 - The Lazy Song.mp3","trackType":"mp3"} Jul 02 23:09:47 volumion1 volumio[8654]: verbose: CURRENT POSITION 5 Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService pause Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus pause Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:09:47 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:09:47 volumion1 volumio[8654]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:09:47 volumion1 volumio[8654]: info: ------------------------------ 100ms Jul 02 23:10:00 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:00 volumion1 volumio[8654]: info: Jul 02 23:10:00 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:10:00 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:00 volumion1 volumio[8654]: info: Jul 02 23:10:00 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:10:00 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:00 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:10:00 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:00 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:10:00 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:00 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:01 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:01 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:10:01 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1486,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Lazy Song","artist":"Bruno Mars","album":"Doo - Wops & Hooligans","uri":"USB/MUSIC/05 - The Lazy Song.mp3","trackType":"mp3"} Jul 02 23:10:01 volumion1 volumio[8654]: verbose: CURRENT POSITION 5 Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService pause Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus pause Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:01 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:10:01 volumion1 volumio[8654]: info: ------------------------------ 97ms Jul 02 23:10:01 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:01 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:01 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:10:01 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1486,"duration":196,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Lazy Song","artist":"Bruno Mars","album":"Doo - Wops & Hooligans","uri":"USB/MUSIC/05 - The Lazy Song.mp3","trackType":"mp3"} Jul 02 23:10:01 volumion1 volumio[8654]: verbose: CURRENT POSITION 5 Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService pause Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus pause Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:01 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:01 volumion1 volumio[8654]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:10:01 volumion1 volumio[8654]: info: ------------------------------ 105ms Jul 02 23:10:02 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::ClearQueue Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::stop Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 23:10:06 volumion1 volumio[8654]: UNSET VOLATILE Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::updateTrackBlock Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::getTrackBlock Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::stPlaybackTimer Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::serviceStop Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 5 Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::serviceStop Jul 02 23:10:06 volumion1 volumio[8654]: info: ControllerMpd::stop Jul 02 23:10:06 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::clearPlayQueue Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::saveQueue Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushQueue Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::addQueueItems Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::addQueueItems Jul 02 23:10:06 volumion1 volumio[8654]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012 Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::saveQueue Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushQueue Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::updateTrackBlock Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::getTrackBlock Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPlay Jul 02 23:10:06 volumion1 volumio[8654]: UNSET VOLATILE Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::play index 0 Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::stop Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 23:10:06 volumion1 volumio[8654]: UNSET VOLATILE Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::play index undefined Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:06 volumion1 volumio[8654]: info: CoreStateMachine::startPlaybackTimer Jul 02 23:10:06 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:06 volumion1 volumio[8654]: info: [1751497806802] ControllerWebradio::clearAddPlayTrack Jul 02 23:10:06 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 23:10:06 volumion1 volumio[8654]: info: Jul 02 23:10:06 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:10:06 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:06 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:06 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand clear Jul 02 23:10:06 volumion1 volumio[8654]: info: Jul 02 23:10:06 volumion1 volumio[8654]: ---------------------------- MPD announces system playlist update Jul 02 23:10:06 volumion1 volumio[8654]: info: Ignoring MPD Status Update Jul 02 23:10:06 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:06 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:06 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Jul 02 23:10:06 volumion1 volumio[8654]: error: updateQueue error: null Jul 02 23:10:06 volumion1 volumio[8654]: info: ------------------------------ 12ms Jul 02 23:10:06 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:06 volumion1 volumio[8654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 02 23:10:06 volumion1 volumio[8654]: info: ------------------------------ 20ms Jul 02 23:10:07 volumion1 volumio[8654]: info: Jul 02 23:10:07 volumion1 volumio[8654]: ---------------------------- MPD announces system playlist update Jul 02 23:10:07 volumion1 volumio[8654]: info: Ignoring MPD Status Update Jul 02 23:10:07 volumion1 volumio[8654]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 23:10:07 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand play Jul 02 23:10:07 volumion1 volumio[8654]: info: ------------------------------ 5ms Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:11 volumion1 volumio[8654]: info: Jul 02 23:10:11 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:10:11 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:11 volumion1 volumio[8654]: info: Jul 02 23:10:11 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:10:11 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:11 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:10:11 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:11 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:10:11 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:11 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:11 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:11 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:11 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:11 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 3643/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 02 23:10:11 volumion1 volumio[8654]: verbose: CURRENT POSITION 0 Jul 02 23:10:11 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:10:11 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus stop Jul 02 23:10:11 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:11 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:11 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:11 volumion1 volumio[8654]: info: ------------------------------ 75ms Jul 02 23:10:12 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:12 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:12 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:12 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 3643/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 02 23:10:12 volumion1 volumio[8654]: verbose: CURRENT POSITION 0 Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:10:12 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:12 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:12 volumion1 volumio[8654]: info: ------------------------------ 110ms Jul 02 23:10:16 volumion1 volumio[8654]: info: Jul 02 23:10:16 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:10:16 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:16 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:16 volumion1 volumio[8654]: info: Jul 02 23:10:16 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:10:16 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:16 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:16 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:16 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:16 volumion1 volumio[8654]: info: Jul 02 23:10:16 volumion1 volumio[8654]: ---------------------------- MPD announces system playlist update Jul 02 23:10:16 volumion1 volumio[8654]: info: Ignoring MPD Status Update Jul 02 23:10:16 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:16 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:16 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:16 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:16 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Airbourne - Gutsy (Radio Edit)","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 02 23:10:16 volumion1 volumio[8654]: verbose: CURRENT POSITION 0 Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:10:16 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:16 volumion1 volumio[8654]: info: ------------------------------ 42ms Jul 02 23:10:16 volumion1 volumio[8654]: info: ------------------------------ 32ms Jul 02 23:10:16 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:16 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:16 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Airbourne - Gutsy (Radio Edit)","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 02 23:10:16 volumion1 volumio[8654]: verbose: CURRENT POSITION 0 Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:10:16 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:16 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:16 volumion1 volumio[8654]: info: ------------------------------ 58ms Jul 02 23:10:20 volumion1 volumio[8654]: info: Jul 02 23:10:20 volumion1 volumio[8654]: ---------------------------- MPD announces system playlist update Jul 02 23:10:20 volumion1 volumio[8654]: info: Ignoring MPD Status Update Jul 02 23:10:20 volumion1 volumio[8654]: info: Jul 02 23:10:20 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:10:20 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:20 volumion1 volumio[8654]: info: ------------------------------ 6ms Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:20 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:20 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:20 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1735,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Airbourne - Gutsy (Radio Edit)","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 02 23:10:20 volumion1 volumio[8654]: verbose: CURRENT POSITION 0 Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:10:20 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:20 volumion1 volumio[8654]: info: ------------------------------ 28ms Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:20 volumion1 volumio[8654]: info: Jul 02 23:10:20 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:10:20 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:20 volumion1 volumio[8654]: info: Jul 02 23:10:20 volumion1 volumio[8654]: ---------------------------- MPD announces state update: update Jul 02 23:10:20 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:20 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:20 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:20 volumion1 volumio[8654]: info: Command Router : Notfying DB Updatefalse Jul 02 23:10:20 volumion1 volumio[8654]: info: CoreCommandRouter::Close All Modals sent Jul 02 23:10:21 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:21 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:21 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:21 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:21 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2353,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Airbourne - Gutsy (Radio Edit)","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 02 23:10:21 volumion1 volumio[8654]: verbose: CURRENT POSITION 0 Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:10:21 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:21 volumion1 volumio[8654]: info: ------------------------------ 82ms Jul 02 23:10:21 volumion1 volumio[8654]: info: Jul 02 23:10:21 volumion1 volumio[8654]: ---------------------------- MPD announces system playlist update Jul 02 23:10:21 volumion1 volumio[8654]: info: Ignoring MPD Status Update Jul 02 23:10:21 volumion1 volumio[8654]: info: Jul 02 23:10:21 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:10:21 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:21 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:21 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:21 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:21 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2353,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Airbourne - Gutsy (Radio Edit)","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 02 23:10:21 volumion1 volumio[8654]: verbose: CURRENT POSITION 0 Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:10:21 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:21 volumion1 volumio[8654]: info: ------------------------------ 114ms Jul 02 23:10:21 volumion1 volumio[8654]: info: ------------------------------ 25ms Jul 02 23:10:21 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:21 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:21 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:21 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:21 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Airbourne - Gutsy (Radio Edit)","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 02 23:10:21 volumion1 volumio[8654]: verbose: CURRENT POSITION 0 Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:10:21 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:21 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:21 volumion1 volumio[8654]: info: ------------------------------ 52ms Jul 02 23:10:23 volumion1 volumio[8654]: info: Jul 02 23:10:23 volumion1 volumio[8654]: ---------------------------- MPD announces system playlist update Jul 02 23:10:23 volumion1 volumio[8654]: info: Ignoring MPD Status Update Jul 02 23:10:23 volumion1 volumio[8654]: info: Jul 02 23:10:23 volumion1 volumio[8654]: ---------------------------- MPD announces state update: player Jul 02 23:10:23 volumion1 volumio[8654]: info: ControllerMpd::getState Jul 02 23:10:23 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand status Jul 02 23:10:23 volumion1 volumio[8654]: info: ------------------------------ 4ms Jul 02 23:10:23 volumion1 volumio[8654]: verbose: ControllerMpd::parseState Jul 02 23:10:23 volumion1 volumio[8654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 23:10:23 volumion1 volumio[8654]: verbose: ControllerMpd::parseTrackInfo Jul 02 23:10:23 volumion1 volumio[8654]: info: ControllerMpd::pushState Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreCommandRouter::servicePushState Jul 02 23:10:23 volumion1 volumio[8654]: info: CorePlayQueue::getTrack 0 Jul 02 23:10:23 volumion1 volumio[8654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3636,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Airbourne - Gutsy (Radio Edit)","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 02 23:10:23 volumion1 volumio[8654]: verbose: CURRENT POSITION 0 Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreStateMachine::syncState stateService play Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreStateMachine::syncState currentStatus play Jul 02 23:10:23 volumion1 volumio[8654]: info: Received an update from plugin. extracting info from payload Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreStateMachine::pushState Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreCommandRouter::volumioPushState Jul 02 23:10:23 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 23:10:23 volumion1 volumio[8654]: info: ------------------------------ 34ms Jul 02 23:10:33 volumion1 volumio[8654]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 02 23:10:38 volumion1 volumio[8654]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E5%A5%95%E8%BF%85/1997-2007%20%E8%B7%A8%E4%B8%96%E7%BA%AA%E5%9B%BD%E8%AF%AD%E7%B2%BE%E9%80%89/72951944-2505-4f01-8f47-b02345e7341b.jpg' Jul 02 23:10:38 volumion1 volumio[8654]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Aimee%20Allen/A%20Little%20Happiness/18a09bd1-52cb-4ef9-be96-f0582b8ddf4f.jpg' Jul 02 23:10:46 volumion1 volumio[8654]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 23:10:46 volumion1 volumio[8654]: { Error: connect ETIMEDOUT 185.60.219.36:443 Jul 02 23:10:46 volumion1 volumio[8654]: at Object._errnoException (util.js:1022:11) Jul 02 23:10:46 volumion1 volumio[8654]: at _exceptionWithHostPort (util.js:1044:20) Jul 02 23:10:46 volumion1 volumio[8654]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 02 23:10:46 volumion1 volumio[8654]: code: 'ETIMEDOUT', Jul 02 23:10:46 volumion1 volumio[8654]: errno: 'ETIMEDOUT', Jul 02 23:10:46 volumion1 volumio[8654]: syscall: 'connect', Jul 02 23:10:46 volumion1 volumio[8654]: address: '185.60.219.36', Jul 02 23:10:46 volumion1 volumio[8654]: port: 443 } Jul 02 23:10:46 volumion1 volumio[8654]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 23:10:46 volumion1 sudo[9300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 23:09 Jul 02 23:10:46 volumion1 sudo[9300]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"