Jan 09 20:38:05 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jan 09 20:38:05 volumio volumio[1176]: info: [1767958685096] [80s80s] handleBrowseUri curUri: 80s80s/eighties Jan 09 20:38:05 volumio volumio[1176]: info: [1767958685096] [80s80s] getRadioContent url: eighties Jan 09 20:38:05 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:38:07 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::ClearQueue Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::stop Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::stPlaybackTimer Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::updateTrackBlock Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrackBlock Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::serviceStop Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::serviceStop Jan 09 20:38:07 volumio volumio[1176]: info: ControllerMpd::stop Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::clearPlayQueue Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::saveQueue Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::addQueueItems Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::addQueueItems Jan 09 20:38:07 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:38:07 volumio volumio[1176]: info: Adding Item to queue: webeighties/2 Jan 09 20:38:07 volumio volumio[1176]: info: Exploding uri webeighties/2 in service 80s80s Jan 09 20:38:07 volumio volumio[1176]: info: [1767958687671] [80s80s] explodeUri: webeighties/2 Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::saveQueue Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::updateTrackBlock Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrackBlock Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::play index 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::stop Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::startPlaybackTimer Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: [1767958687695] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Jan 09 20:38:07 volumio volumio[1176]: info: [1767958687695] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Jan 09 20:38:07 volumio volumio[1176]: info: Jan 09 20:38:07 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:07 volumio volumio[1176]: info: sendMpdCommand stop took 38 milliseconds Jan 09 20:38:07 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music128/v4/34/1f/26/341f2696-4e7e-96a4-e259-9ddaa05f6243/source/600x600bb.jpg","name":"Snap! - The First the Last Eternity (Till the End) (feat. Summer) (GDC Mix)","title":"The First the Last Eternity (Till the End) (feat. Summer) (GDC Mix)","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"230","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 09 20:38:07 volumio volumio[1176]: verbose: CURRENT POSITION 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::syncState stateService stop Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:07 volumio volumio[1176]: info: No code Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:07 volumio volumio[1176]: info: Jan 09 20:38:07 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:07 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:07 volumio volumio[1176]: info: Jan 09 20:38:07 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:07 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:07 volumio volumio[1176]: info: sendMpdCommand status took 55 milliseconds Jan 09 20:38:07 volumio volumio[1176]: info: sendMpdCommand status took 12 milliseconds Jan 09 20:38:07 volumio volumio[1176]: info: sendMpdCommand status took 11 milliseconds Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:07 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 20:38:07 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:07 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:07 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:07 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:07 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:07 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:07 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:07 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:07 volumio volumio[1176]: info: ------------------------------ 93ms Jan 09 20:38:07 volumio volumio[1176]: info: ------------------------------ 52ms Jan 09 20:38:07 volumio volumio[1176]: info: ------------------------------ 52ms Jan 09 20:38:08 volumio volumio[1176]: info: [1767958688873] [80s80s] received new event containing 2 songs. Jan 09 20:38:08 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 20:38:08 volumio volumio[1176]: info: sendMpdCommand stop took 1 milliseconds Jan 09 20:38:08 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 20:38:08 volumio volumio[1176]: info: Jan 09 20:38:08 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:08 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:08 volumio volumio[1176]: info: sendMpdCommand clear took 1 milliseconds Jan 09 20:38:08 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 09 20:38:08 volumio volumio[1176]: info: Jan 09 20:38:08 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:08 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:08 volumio volumio[1176]: info: Jan 09 20:38:08 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:08 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:08 volumio volumio[1176]: error: updateQueue error: null Jan 09 20:38:08 volumio volumio[1176]: error: updateQueue error: null Jan 09 20:38:08 volumio volumio[1176]: error: updateQueue error: null Jan 09 20:38:08 volumio volumio[1176]: info: ------------------------------ 5ms Jan 09 20:38:08 volumio volumio[1176]: info: sendMpdCommand consume 1 took 4 milliseconds Jan 09 20:38:08 volumio volumio[1176]: info: ------------------------------ 4ms Jan 09 20:38:08 volumio volumio[1176]: info: ------------------------------ 3ms Jan 09 20:38:08 volumio volumio[1176]: info: [1767958688883] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Jan 09 20:38:08 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Jan 09 20:38:08 volumio volumio[1176]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 1 milliseconds Jan 09 20:38:08 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand play Jan 09 20:38:08 volumio volumio[1176]: info: Jan 09 20:38:08 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:08 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:08 volumio volumio[1176]: info: Jan 09 20:38:08 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:08 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:08 volumio volumio[1176]: info: Jan 09 20:38:08 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:08 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:08 volumio volumio[1176]: info: sendMpdCommand play took 4 milliseconds Jan 09 20:38:08 volumio volumio[1176]: info: ------------------------------ 4ms Jan 09 20:38:08 volumio volumio[1176]: info: ------------------------------ 2ms Jan 09 20:38:08 volumio volumio[1176]: info: [1767958688894] [80s80s] Pushing the next song state: Michael Jackson - Liberian Girl Jan 09 20:38:08 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:08 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:08 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:08 volumio volumio[1176]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/c0/6e/b9/c06eb99b-6f5c-c3d6-c382-6801c83387b8/source/600x600bb.jpg","name":"Michael Jackson - Liberian Girl","title":"Liberian Girl","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"188","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 09 20:38:08 volumio volumio[1176]: verbose: CURRENT POSITION 0 Jan 09 20:38:08 volumio volumio[1176]: info: CoreStateMachine::syncState stateService play Jan 09 20:38:08 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Jan 09 20:38:08 volumio volumio[1176]: info: [1767958688896] [80s80s] PlayNextTrack API delay: 30 Jan 09 20:38:08 volumio volumio[1176]: info: [1767958688899] [80s80s] Setting timer to: 19104 milliseconds. Jan 09 20:38:08 volumio volumio[1176]: info: ------------------------------ 10ms Jan 09 20:38:08 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:08 volumio volumio[1176]: info: CorePlayQueue::getTrack 1 Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand status took 7 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand status took 6 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand status took 6 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand status took 4 milliseconds Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand status took 10 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand status took 8 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:11 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:11 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:11 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:11 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:11 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:11 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:11 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:11 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:11 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:11 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:11 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:11 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 21ms Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 21ms Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 19ms Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 18ms Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:11 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:11 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:11 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:11 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:11 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:11 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 20ms Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 18ms Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:11 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:11 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:11 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:11 volumio volumio[1176]: info: Jan 09 20:38:11 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 6ms Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand status took 4 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 4ms Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand status took 3 milliseconds Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 5ms Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand status took 4 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:11 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:11 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:11 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:11 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:11 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:11 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 12ms Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 10ms Jan 09 20:38:11 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 20:38:11 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:11 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:11 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:11 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:11 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:11 volumio volumio[1176]: info: ------------------------------ 11ms Jan 09 20:38:24 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::ClearQueue Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::stop Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::stPlaybackTimer Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::updateTrackBlock Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrackBlock Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::serviceStop Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::serviceStop Jan 09 20:38:24 volumio volumio[1176]: info: ControllerMpd::stop Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::clearPlayQueue Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::saveQueue Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::addQueueItems Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::addQueueItems Jan 09 20:38:24 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:38:24 volumio volumio[1176]: info: Adding Item to queue: webeighties/6 Jan 09 20:38:24 volumio volumio[1176]: info: Exploding uri webeighties/6 in service 80s80s Jan 09 20:38:24 volumio volumio[1176]: info: [1767958704137] [80s80s] explodeUri: webeighties/6 Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::saveQueue Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::updateTrackBlock Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrackBlock Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::play index 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::stop Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::startPlaybackTimer Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: [1767958704156] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/dm/mp3-192/volumio Jan 09 20:38:24 volumio volumio[1176]: info: [1767958704156] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=87&count=2 Jan 09 20:38:24 volumio volumio[1176]: info: Jan 09 20:38:24 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:24 volumio volumio[1176]: info: sendMpdCommand stop took 34 milliseconds Jan 09 20:38:24 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/c0/6e/b9/c06eb99b-6f5c-c3d6-c382-6801c83387b8/source/600x600bb.jpg","name":"Michael Jackson - Liberian Girl","title":"Liberian Girl","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"188","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 09 20:38:24 volumio volumio[1176]: verbose: CURRENT POSITION 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::syncState stateService stop Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:24 volumio volumio[1176]: info: No code Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:24 volumio volumio[1176]: info: Jan 09 20:38:24 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:24 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:24 volumio volumio[1176]: info: Jan 09 20:38:24 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:24 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:24 volumio volumio[1176]: info: sendMpdCommand status took 50 milliseconds Jan 09 20:38:24 volumio volumio[1176]: info: sendMpdCommand status took 15 milliseconds Jan 09 20:38:24 volumio volumio[1176]: info: sendMpdCommand status took 13 milliseconds Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:24 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 20:38:24 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:24 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:24 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:24 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:24 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:24 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:24 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:24 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:24 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:24 volumio volumio[1176]: info: ------------------------------ 86ms Jan 09 20:38:24 volumio volumio[1176]: info: ------------------------------ 49ms Jan 09 20:38:24 volumio volumio[1176]: info: ------------------------------ 49ms Jan 09 20:38:25 volumio volumio[1176]: info: [1767958705336] [80s80s] received new event containing 2 songs. Jan 09 20:38:25 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 20:38:25 volumio volumio[1176]: info: sendMpdCommand stop took 1 milliseconds Jan 09 20:38:25 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 20:38:25 volumio volumio[1176]: info: Jan 09 20:38:25 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:25 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:25 volumio volumio[1176]: info: sendMpdCommand clear took 2 milliseconds Jan 09 20:38:25 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 09 20:38:25 volumio volumio[1176]: info: Jan 09 20:38:25 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:25 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:25 volumio volumio[1176]: info: Jan 09 20:38:25 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:25 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:25 volumio volumio[1176]: error: updateQueue error: null Jan 09 20:38:25 volumio volumio[1176]: error: updateQueue error: null Jan 09 20:38:25 volumio volumio[1176]: error: updateQueue error: null Jan 09 20:38:25 volumio volumio[1176]: info: ------------------------------ 5ms Jan 09 20:38:25 volumio volumio[1176]: info: sendMpdCommand consume 1 took 4 milliseconds Jan 09 20:38:25 volumio volumio[1176]: info: ------------------------------ 3ms Jan 09 20:38:25 volumio volumio[1176]: info: ------------------------------ 3ms Jan 09 20:38:25 volumio volumio[1176]: info: [1767958705345] [80s80s] adding url: http://streams.80s80s.de/dm/mp3-192/volumio Jan 09 20:38:25 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/dm/mp3-192/volumio" Jan 09 20:38:25 volumio volumio[1176]: info: sendMpdCommand add "http://streams.80s80s.de/dm/mp3-192/volumio" took 1 milliseconds Jan 09 20:38:25 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand play Jan 09 20:38:25 volumio volumio[1176]: info: Jan 09 20:38:25 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:25 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:25 volumio volumio[1176]: info: Jan 09 20:38:25 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:25 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:25 volumio volumio[1176]: info: Jan 09 20:38:25 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:25 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:25 volumio volumio[1176]: info: sendMpdCommand play took 5 milliseconds Jan 09 20:38:25 volumio volumio[1176]: info: ------------------------------ 5ms Jan 09 20:38:25 volumio volumio[1176]: info: ------------------------------ 3ms Jan 09 20:38:25 volumio volumio[1176]: info: [1767958705360] [80s80s] Pushing the next song state: Depeche Mode - SLOWBLOW (Darren Price Mix) Jan 09 20:38:25 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:25 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:25 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:25 volumio volumio[1176]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-dm-cover.png","name":"Depeche Mode - SLOWBLOW (Darren Price Mix)","title":"SLOWBLOW (Darren Price Mix)","artist":"80s80s Depeche Mode","album":"","streaming":true,"disableUiControls":true,"duration":"372","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 09 20:38:25 volumio volumio[1176]: verbose: CURRENT POSITION 0 Jan 09 20:38:25 volumio volumio[1176]: info: CoreStateMachine::syncState stateService play Jan 09 20:38:25 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Jan 09 20:38:25 volumio volumio[1176]: info: [1767958705362] [80s80s] PlayNextTrack API delay: 30 Jan 09 20:38:25 volumio volumio[1176]: info: [1767958705365] [80s80s] Setting timer to: 372000 milliseconds. Jan 09 20:38:25 volumio volumio[1176]: info: ------------------------------ 10ms Jan 09 20:38:25 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:25 volumio volumio[1176]: info: CorePlayQueue::getTrack 1 Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand status took 3 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand status took 3 milliseconds Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand status took 7 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand status took 7 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand status took 4 milliseconds Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:27 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:27 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:27 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:27 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:27 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:27 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 16ms Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 15ms Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand status took 7 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:27 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:27 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:27 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:27 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:27 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:27 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:27 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:27 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:27 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 17ms Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 17ms Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 14ms Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:27 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:27 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:27 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 13ms Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:27 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:27 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:27 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:27 volumio volumio[1176]: info: Jan 09 20:38:27 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 10ms Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand status took 5 milliseconds Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 6ms Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand status took 6 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 5ms Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand status took 5 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:27 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:27 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:27 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 14ms Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:27 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:27 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:27 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:27 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:27 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:27 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:27 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:27 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:27 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 16ms Jan 09 20:38:27 volumio volumio[1176]: info: ------------------------------ 14ms Jan 09 20:38:32 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::ClearQueue Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::stop Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::stPlaybackTimer Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::updateTrackBlock Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrackBlock Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::serviceStop Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::serviceStop Jan 09 20:38:32 volumio volumio[1176]: info: ControllerMpd::stop Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::clearPlayQueue Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::saveQueue Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::addQueueItems Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::addQueueItems Jan 09 20:38:32 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:38:32 volumio volumio[1176]: info: Adding Item to queue: webeighties/4 Jan 09 20:38:32 volumio volumio[1176]: info: Exploding uri webeighties/4 in service 80s80s Jan 09 20:38:32 volumio volumio[1176]: info: [1767958712704] [80s80s] explodeUri: webeighties/4 Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::saveQueue Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::updateTrackBlock Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrackBlock Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::play index 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::stop Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::startPlaybackTimer Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: [1767958712729] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/wave/mp3-192/volumio Jan 09 20:38:32 volumio volumio[1176]: info: [1767958712729] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=284&count=2 Jan 09 20:38:32 volumio volumio[1176]: info: Jan 09 20:38:32 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:32 volumio volumio[1176]: info: sendMpdCommand stop took 36 milliseconds Jan 09 20:38:32 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-dm-cover.png","name":"Depeche Mode - SLOWBLOW (Darren Price Mix)","title":"SLOWBLOW (Darren Price Mix)","artist":"80s80s Depeche Mode","album":"","streaming":true,"disableUiControls":true,"duration":"372","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 09 20:38:32 volumio volumio[1176]: verbose: CURRENT POSITION 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::syncState stateService stop Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:32 volumio volumio[1176]: info: No code Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:32 volumio volumio[1176]: info: Jan 09 20:38:32 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:32 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:32 volumio volumio[1176]: info: Jan 09 20:38:32 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:32 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:32 volumio volumio[1176]: info: sendMpdCommand status took 45 milliseconds Jan 09 20:38:32 volumio volumio[1176]: info: sendMpdCommand status took 18 milliseconds Jan 09 20:38:32 volumio volumio[1176]: info: sendMpdCommand status took 16 milliseconds Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:32 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:32 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:32 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:32 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:32 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:32 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:32 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:32 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:38:32 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:32 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:32 volumio volumio[1176]: info: ------------------------------ 84ms Jan 09 20:38:32 volumio volumio[1176]: info: ------------------------------ 56ms Jan 09 20:38:32 volumio volumio[1176]: info: ------------------------------ 55ms Jan 09 20:38:33 volumio volumio[1176]: info: [1767958713903] [80s80s] received new event containing 2 songs. Jan 09 20:38:33 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 20:38:33 volumio volumio[1176]: info: sendMpdCommand stop took 1 milliseconds Jan 09 20:38:33 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 20:38:33 volumio volumio[1176]: info: Jan 09 20:38:33 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:33 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:33 volumio volumio[1176]: info: sendMpdCommand clear took 1 milliseconds Jan 09 20:38:33 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 09 20:38:33 volumio volumio[1176]: info: Jan 09 20:38:33 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:33 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:33 volumio volumio[1176]: info: Jan 09 20:38:33 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:33 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:33 volumio volumio[1176]: error: updateQueue error: null Jan 09 20:38:33 volumio volumio[1176]: error: updateQueue error: null Jan 09 20:38:33 volumio volumio[1176]: error: updateQueue error: null Jan 09 20:38:33 volumio volumio[1176]: info: ------------------------------ 5ms Jan 09 20:38:33 volumio volumio[1176]: info: sendMpdCommand consume 1 took 4 milliseconds Jan 09 20:38:33 volumio volumio[1176]: info: ------------------------------ 4ms Jan 09 20:38:33 volumio volumio[1176]: info: ------------------------------ 3ms Jan 09 20:38:33 volumio volumio[1176]: info: [1767958713912] [80s80s] adding url: http://streams.80s80s.de/wave/mp3-192/volumio Jan 09 20:38:33 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" Jan 09 20:38:33 volumio volumio[1176]: info: Jan 09 20:38:33 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:33 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:33 volumio volumio[1176]: info: sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" took 2 milliseconds Jan 09 20:38:33 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand play Jan 09 20:38:33 volumio volumio[1176]: info: Jan 09 20:38:33 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:33 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:33 volumio volumio[1176]: info: Jan 09 20:38:33 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:33 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:33 volumio volumio[1176]: info: ------------------------------ 12ms Jan 09 20:38:33 volumio volumio[1176]: info: sendMpdCommand play took 4 milliseconds Jan 09 20:38:33 volumio volumio[1176]: info: ------------------------------ 4ms Jan 09 20:38:33 volumio volumio[1176]: info: ------------------------------ 3ms Jan 09 20:38:33 volumio volumio[1176]: info: [1767958713926] [80s80s] Pushing the next song state: Visage - The Damned Don't Cry Jan 09 20:38:33 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:33 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:33 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:33 volumio volumio[1176]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/80/56/08/80560824-a15a-bc9e-6504-0c4920828a0e/source/600x600bb.jpg","name":"Visage - The Damned Don't Cry","title":"The Damned Don't Cry","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"270","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 09 20:38:33 volumio volumio[1176]: verbose: CURRENT POSITION 0 Jan 09 20:38:33 volumio volumio[1176]: info: CoreStateMachine::syncState stateService play Jan 09 20:38:33 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Jan 09 20:38:33 volumio volumio[1176]: info: [1767958713927] [80s80s] PlayNextTrack API delay: 30 Jan 09 20:38:33 volumio volumio[1176]: info: [1767958713930] [80s80s] Setting timer to: 270000 milliseconds. Jan 09 20:38:33 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:33 volumio volumio[1176]: info: CorePlayQueue::getTrack 1 Jan 09 20:38:35 volumio volumio[1176]: info: Jan 09 20:38:35 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:35 volumio volumio[1176]: info: Jan 09 20:38:35 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:35 volumio volumio[1176]: info: Jan 09 20:38:35 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:35 volumio volumio[1176]: info: Jan 09 20:38:35 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand status took 3 milliseconds Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:35 volumio volumio[1176]: info: Jan 09 20:38:35 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:35 volumio volumio[1176]: info: Jan 09 20:38:35 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand status took 6 milliseconds Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand status took 6 milliseconds Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand status took 5 milliseconds Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand status took 3 milliseconds Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:35 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:35 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:35 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:35 volumio volumio[1176]: info: ------------------------------ 14ms Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand status took 7 milliseconds Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 20:38:35 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:35 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:35 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:35 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:35 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:35 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:35 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:35 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:35 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:35 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:35 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:35 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:35 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:35 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:35 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:35 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:35 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:35 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:35 volumio volumio[1176]: info: ------------------------------ 18ms Jan 09 20:38:35 volumio volumio[1176]: info: ------------------------------ 17ms Jan 09 20:38:35 volumio volumio[1176]: info: ------------------------------ 16ms Jan 09 20:38:35 volumio volumio[1176]: info: ------------------------------ 14ms Jan 09 20:38:35 volumio volumio[1176]: info: ------------------------------ 13ms Jan 09 20:38:36 volumio volumio[1176]: info: Jan 09 20:38:36 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:36 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:36 volumio volumio[1176]: info: Jan 09 20:38:36 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:36 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:36 volumio volumio[1176]: info: Jan 09 20:38:36 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:36 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:36 volumio volumio[1176]: info: Jan 09 20:38:36 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:36 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:36 volumio volumio[1176]: info: Jan 09 20:38:36 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:36 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:36 volumio volumio[1176]: info: Jan 09 20:38:36 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:36 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:36 volumio volumio[1176]: info: ------------------------------ 5ms Jan 09 20:38:36 volumio volumio[1176]: info: sendMpdCommand status took 4 milliseconds Jan 09 20:38:36 volumio volumio[1176]: info: ------------------------------ 7ms Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:36 volumio volumio[1176]: info: sendMpdCommand status took 9 milliseconds Jan 09 20:38:36 volumio volumio[1176]: info: ------------------------------ 8ms Jan 09 20:38:36 volumio volumio[1176]: info: sendMpdCommand status took 8 milliseconds Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:36 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 20:38:36 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 09 20:38:36 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:36 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:36 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:36 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:36 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:36 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:36 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:36 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:36 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:36 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:36 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:36 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:36 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:36 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:36 volumio volumio[1176]: info: ------------------------------ 17ms Jan 09 20:38:36 volumio volumio[1176]: info: ------------------------------ 16ms Jan 09 20:38:36 volumio volumio[1176]: info: ------------------------------ 14ms Jan 09 20:38:42 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:42 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:42 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:42 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:43 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:43 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:43 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:43 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:44 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:44 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:44 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:44 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:44 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:44 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:44 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:44 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:44 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:44 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:44 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:44 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:45 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:45 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:45 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:45 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:53 volumio volumio[1176]: info: Jan 09 20:38:53 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:53 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:53 volumio volumio[1176]: info: Jan 09 20:38:53 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:53 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:53 volumio volumio[1176]: info: Jan 09 20:38:53 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:53 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:53 volumio volumio[1176]: info: Jan 09 20:38:53 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:53 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:53 volumio volumio[1176]: info: Jan 09 20:38:53 volumio volumio[1176]: ---------------------------- MPD announces system playlist update Jan 09 20:38:53 volumio volumio[1176]: info: Ignoring MPD Status Update Jan 09 20:38:53 volumio volumio[1176]: info: Jan 09 20:38:53 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:38:53 volumio volumio[1176]: info: ControllerMpd::getState Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:38:53 volumio volumio[1176]: info: ------------------------------ 10ms Jan 09 20:38:53 volumio volumio[1176]: info: sendMpdCommand status took 10 milliseconds Jan 09 20:38:53 volumio volumio[1176]: info: ------------------------------ 9ms Jan 09 20:38:53 volumio volumio[1176]: info: sendMpdCommand status took 10 milliseconds Jan 09 20:38:53 volumio volumio[1176]: info: ------------------------------ 10ms Jan 09 20:38:53 volumio volumio[1176]: info: sendMpdCommand status took 8 milliseconds Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::parseState Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:38:53 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:53 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:53 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:53 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:53 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:53 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:53 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:53 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:53 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:53 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:53 volumio volumio[1176]: info: ------------------------------ 17ms Jan 09 20:38:53 volumio volumio[1176]: info: ------------------------------ 16ms Jan 09 20:38:53 volumio volumio[1176]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 09 20:38:53 volumio volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:38:53 volumio volumio[1176]: info: ControllerMpd::pushState Jan 09 20:38:53 volumio volumio[1176]: info: CoreCommandRouter::servicePushState Jan 09 20:38:53 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:53 volumio volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 09 20:38:53 volumio volumio[1176]: info: ------------------------------ 18ms Jan 09 20:38:54 volumio volumio[1176]: info: CoreCommandRouter::volumioPlay Jan 09 20:38:54 volumio volumio[1176]: info: CoreStateMachine::play index undefined Jan 09 20:38:54 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:38:54 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:38:57 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 20:38:57 volumio volumio[1176]: info: CURURI: music-library Jan 09 20:38:57 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:38:59 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 20:38:59 volumio volumio[1176]: info: CURURI: music-library/USB Jan 09 20:38:59 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:39:00 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:39:00 volumio volumio[1176]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 09 20:39:00 volumio volumio[1176]: info: CoreStateMachine::ClearQueue Jan 09 20:39:00 volumio volumio[1176]: info: CoreStateMachine::stop Jan 09 20:39:00 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 20:39:00 volumio volumio[1176]: info: CoreStateMachine::stPlaybackTimer Jan 09 20:39:00 volumio volumio[1176]: info: CoreStateMachine::updateTrackBlock Jan 09 20:39:00 volumio volumio[1176]: info: CorePlayQueue::getTrackBlock Jan 09 20:39:00 volumio volumio[1176]: info: CoreStateMachine::pushState Jan 09 20:39:00 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:39:00 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 20:39:00 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState Jan 09 20:39:00 volumio volumio[1176]: info: CoreStateMachine::serviceStop Jan 09 20:39:00 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Jan 09 20:39:00 volumio volumio[1176]: info: CoreCommandRouter::serviceStop Jan 09 20:39:00 volumio volumio[1176]: info: ControllerMpd::stop Jan 09 20:39:00 volumio volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 20:39:00 volumio volumio[1176]: info: CorePlayQueue::clearPlayQueue Jan 09 20:39:00 volumio volumio[1176]: info: CorePlayQueue::saveQueue Jan 09 20:39:00 volumio volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jan 09 20:39:00 volumio volumio[1176]: info: CoreStateMachine::addQueueItems Jan 09 20:39:00 volumio volumio[1176]: info: CorePlayQueue::addQueueItems Jan 09 20:39:00 volumio volumio[1176]: info: Preload queue cleared Jan 09 20:39:00 volumio volumio[1176]: info: Adding Item to queue: music-library/USB/FLAC-USB Jan 09 20:39:00 volumio volumio[1176]: info: Exploding uri music-library/USB/FLAC-USB in service mpd Jan 09 20:39:01 volumio volumio[1176]: info: Jan 09 20:39:01 volumio volumio[1176]: ---------------------------- MPD announces state update: player Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny/Offramp%20%5B2010%20GER%20180g%20Vinyl%5D/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1982%20-%20Pat%20Metheny%20Group%20-%20Offramp%20(2010%2C%20ECM%2C%20ECM%201216%2C%20DE%2C%2024-96)%2F01%20Barcarole.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1982 - Pat Metheny Group - Offramp (2010, ECM, ECM 1216, DE, 24-96)/01 Barcarole.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny/Offramp%20%5B2010%20GER%20180g%20Vinyl%5D/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1982%20-%20Pat%20Metheny%20Group%20-%20Offramp%20(2010%2C%20ECM%2C%20ECM%201216%2C%20DE%2C%2024-96)%2F02%20Are%20You%20Going%20With%20Me_.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1982 - Pat Metheny Group - Offramp (2010, ECM, ECM 1216, DE, 24-96)/02 Are You Going With Me_.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny/Offramp%20%5B2010%20GER%20180g%20Vinyl%5D/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1982%20-%20Pat%20Metheny%20Group%20-%20Offramp%20(2010%2C%20ECM%2C%20ECM%201216%2C%20DE%2C%2024-96)%2F03%20Au%20Lait.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1982 - Pat Metheny Group - Offramp (2010, ECM, ECM 1216, DE, 24-96)/03 Au Lait.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny/Offramp%20%5B2010%20GER%20180g%20Vinyl%5D/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1982%20-%20Pat%20Metheny%20Group%20-%20Offramp%20(2010%2C%20ECM%2C%20ECM%201216%2C%20DE%2C%2024-96)%2F04%20Eighteen.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1982 - Pat Metheny Group - Offramp (2010, ECM, ECM 1216, DE, 24-96)/04 Eighteen.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny/Offramp%20%5B2010%20GER%20180g%20Vinyl%5D/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1982%20-%20Pat%20Metheny%20Group%20-%20Offramp%20(2010%2C%20ECM%2C%20ECM%201216%2C%20DE%2C%2024-96)%2F05%20Offramp.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1982 - Pat Metheny Group - Offramp (2010, ECM, ECM 1216, DE, 24-96)/05 Offramp.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny/Offramp%20%5B2010%20GER%20180g%20Vinyl%5D/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1982%20-%20Pat%20Metheny%20Group%20-%20Offramp%20(2010%2C%20ECM%2C%20ECM%201216%2C%20DE%2C%2024-96)%2F06%20James.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1982 - Pat Metheny Group - Offramp (2010, ECM, ECM 1216, DE, 24-96)/06 James.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny/Offramp%20%5B2010%20GER%20180g%20Vinyl%5D/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1982%20-%20Pat%20Metheny%20Group%20-%20Offramp%20(2010%2C%20ECM%2C%20ECM%201216%2C%20DE%2C%2024-96)%2F07%20The%20Bat%20Part%20II.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1982 - Pat Metheny Group - Offramp (2010, ECM, ECM 1216, DE, 24-96)/07 The Bat Part II.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F01%20Have%20You%20Heard.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/01 Have You Heard.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F02%20Every%20Summer%20Night.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/02 Every Summer Night.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F03%20Better%20Days%20Ahead.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/03 Better Days Ahead.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F04%20Spring%20Ain't%20Here.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/04 Spring Ain't Here.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F05%2045-8.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/05 45-8.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F06%205-5-7.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/06 5-5-7.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F07%20Beat%2070.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/07 Beat 70.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F08%20Dream%20Of%20The%20Return.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/08 Dream Of The Return.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F09%20Are%20We%20There%20Yet.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/09 Are We There Yet.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F10%20Vidala.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/10 Vidala.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F11%20Slip%20Away.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/11 Slip Away.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F12%20Letter%20From%20Home.flac&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/12 Letter From Home.flac Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F1989%20-%20Pat%20Metheny%20-%20Letter%20From%20Home.cue&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/1989 - Pat Metheny - Letter From Home.cue Jan 09 20:39:01 volumio volumio[1176]: info: ALBUMART /albumart?cacheid=544&web=Pat%20Metheny%20Group/Letter%20From%20Home/extralarge&path=%2Fmnt%2FUSB%2FFLAC-USB%2F1989%20-%20Pat%20Metheny%20Group%20-%20Letter%20From%20Home%20(Geffen%2C%20924%20245-1%2C%20DE%2C%2024-96)%2F1989%20-%20Pat%20Metheny%20-%20Letter%20From%20Home.cue&metadata=false Jan 09 20:39:01 volumio volumio[1176]: info: URI /mnt/USB/FLAC-USB/1989 - Pat Metheny Group - Letter From Home (Geffen, 924 245-1, DE, 24-96)/1989 - Pat Metheny - Letter From Home.cue Jan 09 20:39:01 volumio volumio[1176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 20:39:01 volumio volumio[1176]: Error: Unable to resolve or reject the same promise twice Jan 09 20:39:01 volumio volumio[1176]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 09 20:39:01 volumio volumio[1176]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Jan 09 20:39:01 volumio volumio[1176]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 09 20:39:01 volumio volumio[1176]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 09 20:39:01 volumio volumio[1176]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 09 20:39:01 volumio volumio[1176]: at Socket.emit (node:events:514:28) Jan 09 20:39:01 volumio volumio[1176]: at addChunk (node:internal/streams/readable:343:12) Jan 09 20:39:01 volumio volumio[1176]: at readableAddChunk (node:internal/streams/readable:312:11) Jan 09 20:39:01 volumio volumio[1176]: at Readable.push (node:internal/streams/readable:253:10) Jan 09 20:39:01 volumio volumio[1176]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jan 09 20:39:01 volumio volumio[1176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 20:39:02 volumio sudo[4096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-09 20:38' Jan 09 20:39:02 volumio sudo[4096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"