Jan 27 09:48:00 volumio go-librespot[2004]: time="2026-01-27T09:48:00-06:00" level=trace msg="sent dealer ping" Jan 27 09:48:00 volumio go-librespot[2004]: time="2026-01-27T09:48:00-06:00" level=trace msg="received dealer pong" Jan 27 09:48:08 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::ClearQueue Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::stPlaybackTimer Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::serviceStop Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::serviceStop Jan 27 09:48:08 volumio volumio[1296]: info: ControllerMpd::stop Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::clearPlayQueue Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::addQueueItems Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::addQueueItems Jan 27 09:48:08 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:08 volumio volumio[1296]: info: Adding Item to queue: webeighties/0 Jan 27 09:48:08 volumio volumio[1296]: info: Using cached record of: webeighties/0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPlay Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::play index 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::play index undefined Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::startPlaybackTimer Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: [1769528888026] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Jan 27 09:48:08 volumio volumio[1296]: info: [1769528888026] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:08 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand stop took 25 milliseconds Jan 27 09:48:08 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music3/v4/ba/51/67/ba5167d0-9a99-a164-92da-67b31a8b625b/source/600x600bb.jpg","name":"Spagna - Call Me","title":"Call Me","artist":"80s80s Italo Disco","album":"","streaming":true,"disableUiControls":true,"duration":"239","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:08 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::syncState stateService stop Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:08 volumio volumio[1296]: info: No code Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:08 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:08 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:08 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:08 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:08 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:08 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand status took 37 milliseconds Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand status took 29 milliseconds Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand status took 27 milliseconds Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand status took 24 milliseconds Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:08 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:08 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:08 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:08 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 79ms Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 70ms Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 66ms Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 64ms Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:08 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:08 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:08 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:08 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:08 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:08 volumio volumio[1296]: info: [1769528888444] [80s80s] received new event containing 2 songs. Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand stop took 3 milliseconds Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:08 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand clear took 2 milliseconds Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:08 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:08 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:08 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:08 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:08 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:08 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:08 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 23ms Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand consume 1 took 23 milliseconds Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 22ms Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 22ms Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 22ms Jan 27 09:48:08 volumio volumio[1296]: info: [1769528888477] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:08 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 4 milliseconds Jan 27 09:48:08 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand play Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:08 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:08 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:08 volumio volumio[1296]: info: Jan 27 09:48:08 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:08 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 15ms Jan 27 09:48:08 volumio volumio[1296]: info: sendMpdCommand play took 7 milliseconds Jan 27 09:48:08 volumio volumio[1296]: info: [1769528888496] [80s80s] Pushing the next song state: Run-DMC - It's Like That Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music/8b/97/8a/mzi.lvgswlpd.jpg/600x600bb.jpg","name":"Run-DMC - It's Like That","title":"It's Like That","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"277","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:08 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::syncState stateService play Jan 27 09:48:08 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:08 volumio volumio[1296]: info: [1769528888498] [80s80s] PlayNextTrack API delay: 30 Jan 27 09:48:08 volumio volumio[1296]: info: [1769528888499] [80s80s] Setting timer to: 277000 milliseconds. Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 12ms Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:08 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:08 volumio volumio[1296]: info: CorePlayQueue::getTrack 1 Jan 27 09:48:08 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 27 09:48:09 volumio volumio[1296]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 27 09:48:09 volumio volumio[1296]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 39 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 40 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 39 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 39 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 11 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 7 milliseconds Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 14 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 13 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 58ms Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 57ms Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 57ms Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 56ms Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 31ms Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 29ms Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 30ms Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 27ms Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:09 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:09 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:09 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:09 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:09 volumio volumio[1296]: info: Jan 27 09:48:09 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 14ms Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 13 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 14ms Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 13 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 14ms Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 14 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 14ms Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand status took 7 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 20ms Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 20ms Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 19ms Jan 27 09:48:09 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:48:09 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:09 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:09 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:09 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:09 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:09 volumio volumio[1296]: info: ------------------------------ 10ms Jan 27 09:48:10 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 27 09:48:10 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 27 09:48:26 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::ClearQueue Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::stPlaybackTimer Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::serviceStop Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::serviceStop Jan 27 09:48:26 volumio volumio[1296]: info: ControllerMpd::stop Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::clearPlayQueue Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::addQueueItems Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::addQueueItems Jan 27 09:48:26 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:26 volumio volumio[1296]: info: Adding Item to queue: webeighties/1 Jan 27 09:48:26 volumio volumio[1296]: info: Exploding uri webeighties/1 in service 80s80s Jan 27 09:48:26 volumio volumio[1296]: info: [1769528906309] [80s80s] explodeUri: webeighties/1 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPlay Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::play index 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::play index undefined Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::startPlaybackTimer Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: [1769528906326] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/ndw/mp3-192/volumio Jan 27 09:48:26 volumio volumio[1296]: info: [1769528906326] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=137&count=2 Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:26 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand stop took 28 milliseconds Jan 27 09:48:26 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music/8b/97/8a/mzi.lvgswlpd.jpg/600x600bb.jpg","name":"Run-DMC - It's Like That","title":"It's Like That","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"277","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:26 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::syncState stateService stop Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:26 volumio volumio[1296]: info: No code Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:26 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:26 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:26 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:26 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:26 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:26 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand status took 37 milliseconds Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand status took 12 milliseconds Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand status took 11 milliseconds Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand status took 10 milliseconds Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:26 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:26 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:26 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:26 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 74ms Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 50ms Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 48ms Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 47ms Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:26 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:26 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:26 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:26 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:26 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:26 volumio volumio[1296]: info: [1769528906777] [80s80s] received new event containing 2 songs. Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand stop took 1 milliseconds Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:26 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand clear took 3 milliseconds Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:26 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:26 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:26 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:26 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:26 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:26 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:26 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 13ms Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand consume 1 took 10 milliseconds Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 6ms Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 5ms Jan 27 09:48:26 volumio volumio[1296]: info: [1769528906797] [80s80s] adding url: http://streams.80s80s.de/ndw/mp3-192/volumio Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/ndw/mp3-192/volumio" Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:26 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand add "http://streams.80s80s.de/ndw/mp3-192/volumio" took 4 milliseconds Jan 27 09:48:26 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand play Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:26 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:26 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:26 volumio volumio[1296]: info: Jan 27 09:48:26 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:26 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 14ms Jan 27 09:48:26 volumio volumio[1296]: info: sendMpdCommand play took 7 milliseconds Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 6ms Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 3ms Jan 27 09:48:26 volumio volumio[1296]: info: ------------------------------ 3ms Jan 27 09:48:26 volumio volumio[1296]: info: [1769528906812] [80s80s] Pushing the next song state: Joachim Witt - Goldener Reiter Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music115/v4/3a/1d/d0/3a1dd0e5-3e8c-f298-9401-db3aae8a0b87/source/600x600bb.jpg","name":"Joachim Witt - Goldener Reiter","title":"Goldener Reiter","artist":"80s80s NDW","album":"","streaming":true,"disableUiControls":true,"duration":"251","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:26 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::syncState stateService play Jan 27 09:48:26 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:26 volumio volumio[1296]: info: [1769528906813] [80s80s] PlayNextTrack API delay: 30 Jan 27 09:48:26 volumio volumio[1296]: info: [1769528906816] [80s80s] Setting timer to: 251000 milliseconds. Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:26 volumio volumio[1296]: info: CorePlayQueue::getTrack 1 Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 27 09:48:28 volumio volumio[1296]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 27 09:48:28 volumio volumio[1296]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 19 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 19 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 19 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 7 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 7 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 7 milliseconds Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 32ms Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 31ms Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 31ms Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 22ms Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 17ms Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 15ms Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 14ms Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 13ms Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:28 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:28 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:28 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:28 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:28 volumio volumio[1296]: info: Jan 27 09:48:28 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 15ms Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 14 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 14ms Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 13 milliseconds Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 16ms Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 8ms Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand status took 10 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 26ms Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 25ms Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 19ms Jan 27 09:48:28 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:48:28 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:28 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:28 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:28 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:28 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:28 volumio volumio[1296]: info: ------------------------------ 16ms Jan 27 09:48:30 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::ClearQueue Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::stPlaybackTimer Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::serviceStop Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::serviceStop Jan 27 09:48:30 volumio volumio[1296]: info: ControllerMpd::stop Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::clearPlayQueue Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::addQueueItems Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::addQueueItems Jan 27 09:48:30 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:30 volumio volumio[1296]: info: Adding Item to queue: webeighties/2 Jan 27 09:48:30 volumio volumio[1296]: info: Exploding uri webeighties/2 in service 80s80s Jan 27 09:48:30 volumio volumio[1296]: info: [1769528910506] [80s80s] explodeUri: webeighties/2 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPlay Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::play index 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::play index undefined Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::startPlaybackTimer Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: [1769528910517] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Jan 27 09:48:30 volumio volumio[1296]: info: [1769528910517] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:30 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand stop took 23 milliseconds Jan 27 09:48:30 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music115/v4/3a/1d/d0/3a1dd0e5-3e8c-f298-9401-db3aae8a0b87/source/600x600bb.jpg","name":"Joachim Witt - Goldener Reiter","title":"Goldener Reiter","artist":"80s80s NDW","album":"","streaming":true,"disableUiControls":true,"duration":"251","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:30 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::syncState stateService stop Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:30 volumio volumio[1296]: info: No code Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:30 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:30 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:30 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:30 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:30 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:30 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand status took 41 milliseconds Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand status took 26 milliseconds Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand status took 16 milliseconds Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand status took 15 milliseconds Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:30 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:30 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:30 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:30 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 80ms Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 67ms Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 64ms Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 54ms Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:30 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:30 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:30 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:30 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:30 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:30 volumio volumio[1296]: info: [1769528910691] [80s80s] received new event containing 2 songs. Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand stop took 1 milliseconds Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:30 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand clear took 3 milliseconds Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:30 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:30 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:30 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:30 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:30 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 7ms Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand consume 1 took 6 milliseconds Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 5ms Jan 27 09:48:30 volumio volumio[1296]: info: [1769528910703] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Jan 27 09:48:30 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:30 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:30 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 6ms Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 4ms Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 3 milliseconds Jan 27 09:48:30 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand play Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:30 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:30 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:30 volumio volumio[1296]: info: Jan 27 09:48:30 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:30 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:30 volumio volumio[1296]: info: sendMpdCommand play took 6 milliseconds Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 4ms Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 3ms Jan 27 09:48:30 volumio volumio[1296]: info: ------------------------------ 2ms Jan 27 09:48:30 volumio volumio[1296]: info: [1769528910714] [80s80s] Pushing the next song state: U2 - With or Without You Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music115/v4/bf/15/9b/bf159be9-12c0-ec64-bf73-58188cdb339e/source/600x600bb.jpg","name":"U2 - With or Without You","title":"With or Without You","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"265","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:30 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::syncState stateService play Jan 27 09:48:30 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:30 volumio volumio[1296]: info: [1769528910715] [80s80s] PlayNextTrack API delay: 30 Jan 27 09:48:30 volumio volumio[1296]: info: [1769528910716] [80s80s] Setting timer to: 265000 milliseconds. Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:30 volumio volumio[1296]: info: CorePlayQueue::getTrack 1 Jan 27 09:48:30 volumio go-librespot[2004]: time="2026-01-27T09:48:30-06:00" level=trace msg="sent dealer ping" Jan 27 09:48:30 volumio go-librespot[2004]: time="2026-01-27T09:48:30-06:00" level=trace msg="received dealer pong" Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 27 09:48:32 volumio volumio[1296]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 27 09:48:32 volumio volumio[1296]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 12 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 11 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 11 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 11 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 4 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 3 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 3 milliseconds Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 36ms Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 27ms Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 34ms Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 33ms Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 28ms Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 23ms Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 21ms Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 21ms Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:32 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:32 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:32 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:32 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:32 volumio volumio[1296]: info: Jan 27 09:48:32 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 11ms Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 10 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 10ms Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 10 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 10ms Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 21ms Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:32 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:32 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:32 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:32 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 29ms Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 29ms Jan 27 09:48:32 volumio volumio[1296]: info: ------------------------------ 28ms Jan 27 09:48:34 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::ClearQueue Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::stPlaybackTimer Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::serviceStop Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::serviceStop Jan 27 09:48:34 volumio volumio[1296]: info: ControllerMpd::stop Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::clearPlayQueue Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::addQueueItems Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::addQueueItems Jan 27 09:48:34 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:34 volumio volumio[1296]: info: Adding Item to queue: webeighties/3 Jan 27 09:48:34 volumio volumio[1296]: info: Exploding uri webeighties/3 in service 80s80s Jan 27 09:48:34 volumio volumio[1296]: info: [1769528914023] [80s80s] explodeUri: webeighties/3 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPlay Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::play index 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::play index undefined Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::startPlaybackTimer Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: [1769528914037] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/party/mp3-192/volumio Jan 27 09:48:34 volumio volumio[1296]: info: [1769528914037] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=252&count=2 Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:34 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand stop took 25 milliseconds Jan 27 09:48:34 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music115/v4/bf/15/9b/bf159be9-12c0-ec64-bf73-58188cdb339e/source/600x600bb.jpg","name":"U2 - With or Without You","title":"With or Without You","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"265","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:34 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::syncState stateService stop Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:34 volumio volumio[1296]: info: No code Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:34 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:34 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:34 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:34 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:34 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:34 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand status took 47 milliseconds Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand status took 32 milliseconds Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand status took 27 milliseconds Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand status took 25 milliseconds Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:34 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:34 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:34 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:34 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 73ms Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 58ms Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 56ms Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 55ms Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:34 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:34 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:34 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:34 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:34 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:34 volumio volumio[1296]: info: [1769528914238] [80s80s] received new event containing 2 songs. Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand stop took 1 milliseconds Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:34 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand clear took 2 milliseconds Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:34 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:34 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:34 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:34 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:34 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:34 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:34 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand consume 1 took 10 milliseconds Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 8ms Jan 27 09:48:34 volumio volumio[1296]: info: [1769528914259] [80s80s] adding url: http://streams.80s80s.de/party/mp3-192/volumio Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:34 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" took 6 milliseconds Jan 27 09:48:34 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand play Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:34 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:34 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:34 volumio volumio[1296]: info: Jan 27 09:48:34 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:34 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 13ms Jan 27 09:48:34 volumio volumio[1296]: info: sendMpdCommand play took 6 milliseconds Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 5ms Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 2ms Jan 27 09:48:34 volumio volumio[1296]: info: ------------------------------ 1ms Jan 27 09:48:34 volumio volumio[1296]: info: [1769528914277] [80s80s] Pushing the next song state: Evelyn Thomas - High Energy Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music/v4/17/e7/16/17e716e5-9fd9-ecdc-09b1-8e18c4290331/source/600x600bb.jpg","name":"Evelyn Thomas - High Energy","title":"High Energy","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"213","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:34 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::syncState stateService play Jan 27 09:48:34 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:34 volumio volumio[1296]: info: [1769528914282] [80s80s] PlayNextTrack API delay: 30 Jan 27 09:48:34 volumio volumio[1296]: info: [1769528914287] [80s80s] Setting timer to: 213000 milliseconds. Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:34 volumio volumio[1296]: info: CorePlayQueue::getTrack 1 Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 27 09:48:35 volumio volumio[1296]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 27 09:48:35 volumio volumio[1296]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 13 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 12 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 4 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 19ms Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 19ms Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 5 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 4 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 5 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 22ms Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 19ms Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 11ms Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 10ms Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 10ms Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:35 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:35 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:35 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:35 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:35 volumio volumio[1296]: info: Jan 27 09:48:35 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 12ms Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 11 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 11ms Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 12ms Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 7ms Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand status took 6 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 20ms Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 20ms Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:35 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:35 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:35 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:35 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 16ms Jan 27 09:48:35 volumio volumio[1296]: info: ------------------------------ 11ms Jan 27 09:48:51 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::ClearQueue Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::stPlaybackTimer Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::serviceStop Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::serviceStop Jan 27 09:48:51 volumio volumio[1296]: info: ControllerMpd::stop Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::clearPlayQueue Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::addQueueItems Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::addQueueItems Jan 27 09:48:51 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:51 volumio volumio[1296]: info: Adding Item to queue: webeighties/4 Jan 27 09:48:51 volumio volumio[1296]: info: Exploding uri webeighties/4 in service 80s80s Jan 27 09:48:51 volumio volumio[1296]: info: [1769528931854] [80s80s] explodeUri: webeighties/4 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPlay Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::play index 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::play index undefined Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::startPlaybackTimer Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: [1769528931869] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/wave/mp3-192/volumio Jan 27 09:48:51 volumio volumio[1296]: info: [1769528931869] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=284&count=2 Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:51 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:51 volumio volumio[1296]: info: Jan 27 09:48:51 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:51 volumio volumio[1296]: info: sendMpdCommand stop took 27 milliseconds Jan 27 09:48:51 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music/v4/17/e7/16/17e716e5-9fd9-ecdc-09b1-8e18c4290331/source/600x600bb.jpg","name":"Evelyn Thomas - High Energy","title":"High Energy","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"213","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:51 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::syncState stateService stop Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:51 volumio volumio[1296]: info: No code Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:51 volumio volumio[1296]: info: Jan 27 09:48:51 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:51 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:51 volumio volumio[1296]: info: Jan 27 09:48:51 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:51 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:51 volumio volumio[1296]: info: Jan 27 09:48:51 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:51 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:51 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:51 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:51 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:51 volumio volumio[1296]: info: sendMpdCommand status took 42 milliseconds Jan 27 09:48:51 volumio volumio[1296]: info: sendMpdCommand status took 22 milliseconds Jan 27 09:48:51 volumio volumio[1296]: info: sendMpdCommand status took 21 milliseconds Jan 27 09:48:51 volumio volumio[1296]: info: sendMpdCommand status took 18 milliseconds Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:51 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 27 09:48:51 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 27 09:48:51 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 27 09:48:51 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:51 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:51 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:51 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:51 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:51 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:51 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:51 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:51 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:51 volumio volumio[1296]: info: ------------------------------ 73ms Jan 27 09:48:51 volumio volumio[1296]: info: ------------------------------ 53ms Jan 27 09:48:51 volumio volumio[1296]: info: ------------------------------ 51ms Jan 27 09:48:51 volumio volumio[1296]: info: ------------------------------ 50ms Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:51 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:51 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:51 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:51 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:51 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:52 volumio volumio[1296]: info: [1769528932204] [80s80s] received new event containing 2 songs. Jan 27 09:48:52 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:52 volumio volumio[1296]: info: sendMpdCommand stop took 3 milliseconds Jan 27 09:48:52 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 09:48:52 volumio volumio[1296]: info: Jan 27 09:48:52 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:52 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:52 volumio volumio[1296]: info: sendMpdCommand clear took 2 milliseconds Jan 27 09:48:52 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 27 09:48:52 volumio volumio[1296]: info: Jan 27 09:48:52 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:52 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:52 volumio volumio[1296]: info: Jan 27 09:48:52 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:52 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:52 volumio volumio[1296]: info: Jan 27 09:48:52 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:52 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:52 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:52 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:52 volumio volumio[1296]: info: ------------------------------ 11ms Jan 27 09:48:52 volumio volumio[1296]: info: sendMpdCommand consume 1 took 9 milliseconds Jan 27 09:48:52 volumio volumio[1296]: info: ------------------------------ 7ms Jan 27 09:48:52 volumio volumio[1296]: info: [1769528932220] [80s80s] adding url: http://streams.80s80s.de/wave/mp3-192/volumio Jan 27 09:48:52 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" Jan 27 09:48:52 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:52 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:52 volumio volumio[1296]: info: Jan 27 09:48:52 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:52 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:52 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:52 volumio volumio[1296]: info: ------------------------------ 5ms Jan 27 09:48:52 volumio volumio[1296]: info: sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" took 2 milliseconds Jan 27 09:48:52 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand play Jan 27 09:48:52 volumio volumio[1296]: info: Jan 27 09:48:52 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:52 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:52 volumio volumio[1296]: info: Jan 27 09:48:52 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:52 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:52 volumio volumio[1296]: info: Jan 27 09:48:52 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:52 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:52 volumio volumio[1296]: info: ------------------------------ 14ms Jan 27 09:48:52 volumio volumio[1296]: info: sendMpdCommand play took 10 milliseconds Jan 27 09:48:52 volumio volumio[1296]: info: ------------------------------ 10ms Jan 27 09:48:52 volumio volumio[1296]: info: ------------------------------ 10ms Jan 27 09:48:52 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:52 volumio volumio[1296]: info: [1769528932240] [80s80s] Pushing the next song state: ABC - Fear of the World Jan 27 09:48:52 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:52 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:52 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:52 volumio volumio[1296]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music118/v4/ca/2d/51/ca2d51a7-393b-5d20-da10-8f9b55921c42/source/600x600bb.jpg","name":"ABC - Fear of the World","title":"Fear of the World","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"271","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:52 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:52 volumio volumio[1296]: info: CoreStateMachine::syncState stateService play Jan 27 09:48:52 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:52 volumio volumio[1296]: info: [1769528932240] [80s80s] PlayNextTrack API delay: 30 Jan 27 09:48:52 volumio volumio[1296]: info: [1769528932241] [80s80s] Setting timer to: 271000 milliseconds. Jan 27 09:48:52 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:52 volumio volumio[1296]: info: CorePlayQueue::getTrack 1 Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: FusionDsp - ---- read samplerate, raw: Jan 27 09:48:53 volumio volumio[1296]: error: FusionDsp - invalid sample rate Jan 27 09:48:53 volumio volumio[1296]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 27 09:48:53 volumio volumio[1296]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 27 09:48:53 volumio volumio[1296]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 20 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 19 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 18 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 19 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 5 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 36ms Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 34ms Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 34ms Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 33ms Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 13 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 21ms Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 18ms Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 17ms Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 17ms Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:53 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:53 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:53 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:53 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:53 volumio volumio[1296]: info: Jan 27 09:48:53 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 6ms Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 11 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 9 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 9ms Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 28ms Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 27 09:48:53 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:53 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:53 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:53 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 34ms Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 29ms Jan 27 09:48:53 volumio volumio[1296]: info: ------------------------------ 29ms Jan 27 09:48:55 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:55 volumio volumio[1296]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::ClearQueue Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::stPlaybackTimer Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:55 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:55 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::serviceStop Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:55 volumio volumio[1296]: info: CoreCommandRouter::serviceStop Jan 27 09:48:55 volumio volumio[1296]: info: ControllerMpd::stop Jan 27 09:48:55 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::clearPlayQueue Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:55 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::addQueueItems Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::addQueueItems Jan 27 09:48:55 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:48:55 volumio volumio[1296]: info: Adding Item to queue: webeighties/8 Jan 27 09:48:55 volumio volumio[1296]: info: Using cached record of: webeighties/8 Jan 27 09:48:55 volumio volumio[1296]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::saveQueue Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::updateTrackBlock Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::getTrackBlock Jan 27 09:48:55 volumio volumio[1296]: info: CoreCommandRouter::volumioPlay Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::play index 0 Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::stop Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::play index undefined Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:55 volumio volumio[1296]: info: CoreStateMachine::startPlaybackTimer Jan 27 09:48:55 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:55 volumio volumio[1296]: info: [1769528935993] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/bowie/mp3-192/volumio Jan 27 09:48:55 volumio volumio[1296]: info: [1769528935994] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=84&count=2 Jan 27 09:48:55 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:55 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:56 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand stop took 27 milliseconds Jan 27 09:48:56 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music118/v4/ca/2d/51/ca2d51a7-393b-5d20-da10-8f9b55921c42/source/600x600bb.jpg","name":"ABC - Fear of the World","title":"Fear of the World","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"271","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:56 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::syncState stateService stop Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:56 volumio volumio[1296]: info: No code Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:56 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:56 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:56 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:56 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:56 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:56 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand status took 35 milliseconds Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand status took 20 milliseconds Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand status took 18 milliseconds Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand status took 15 milliseconds Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:56 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:56 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:56 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:56 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::pushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::volumioPushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 58ms Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 47ms Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 43ms Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 41ms Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Volumio is not playing Jan 27 09:48:56 volumio volumio[1296]: info: FusionDsp - Clipped samples monitor stopped Jan 27 09:48:56 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:56 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:56 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:56 volumio volumio[1296]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 Jan 27 09:48:56 volumio volumio[1296]: info: [1769528936226] [80s80s] received new event containing 2 songs. Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand stop took 0 milliseconds Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:56 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand clear took 2 milliseconds Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:56 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:56 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:56 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:56 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:56 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:56 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:56 volumio volumio[1296]: error: updateQueue error: null Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 7ms Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand consume 1 took 6 milliseconds Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 5ms Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 4ms Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 3ms Jan 27 09:48:56 volumio volumio[1296]: info: [1769528936238] [80s80s] adding url: http://streams.80s80s.de/bowie/mp3-192/volumio Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/bowie/mp3-192/volumio" Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:56 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand add "http://streams.80s80s.de/bowie/mp3-192/volumio" took 3 milliseconds Jan 27 09:48:56 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand play Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:56 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:56 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:56 volumio volumio[1296]: info: Jan 27 09:48:56 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:56 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 8ms Jan 27 09:48:56 volumio volumio[1296]: info: sendMpdCommand play took 6 milliseconds Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 7ms Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 7ms Jan 27 09:48:56 volumio volumio[1296]: info: ------------------------------ 7ms Jan 27 09:48:56 volumio volumio[1296]: info: [1769528936255] [80s80s] Pushing the next song state: David Bowie - Let's Dance Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/8c/16/e7/8c16e71f-5a5c-797c-efcf-f0550d576892/source/600x600bb.jpg","name":"David Bowie - Let's Dance","title":"Let's Dance","artist":"80s80s David Bowie","album":"","streaming":true,"disableUiControls":true,"duration":"231","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 27 09:48:56 volumio volumio[1296]: verbose: CURRENT POSITION 0 Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::syncState stateService play Jan 27 09:48:56 volumio volumio[1296]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:48:56 volumio volumio[1296]: info: [1769528936258] [80s80s] PlayNextTrack API delay: 30 Jan 27 09:48:56 volumio volumio[1296]: info: [1769528936259] [80s80s] Setting timer to: 231000 milliseconds. Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:56 volumio volumio[1296]: info: CorePlayQueue::getTrack 1 Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 27 09:48:57 volumio volumio[1296]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 27 09:48:57 volumio volumio[1296]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 11 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 12 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 7 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 6 milliseconds Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 7 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 3 milliseconds Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 23ms Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 22ms Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 21ms Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 16ms Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 17 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 16 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 29ms Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 25ms Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 26ms Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 25ms Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:57 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:57 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:57 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces system playlist update Jan 27 09:48:57 volumio volumio[1296]: info: Ignoring MPD Status Update Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: Jan 27 09:48:57 volumio volumio[1296]: ---------------------------- MPD announces state update: player Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 14ms Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::getState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 13ms Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 13ms Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 10ms Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 5 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 5 milliseconds Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand status took 4 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseState Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 13ms Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 13ms Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 10ms Jan 27 09:48:57 volumio volumio[1296]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:48:57 volumio volumio[1296]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:48:57 volumio volumio[1296]: info: ControllerMpd::pushState Jan 27 09:48:57 volumio volumio[1296]: info: CoreCommandRouter::servicePushState Jan 27 09:48:57 volumio volumio[1296]: info: CorePlayQueue::getTrack 0 Jan 27 09:48:57 volumio volumio[1296]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 27 09:48:57 volumio volumio[1296]: info: ------------------------------ 8ms Jan 27 09:48:59 volumio go-librespot[2004]: time="2026-01-27T09:48:59-06:00" level=trace msg="received accesspoint ping" Jan 27 09:49:00 volumio go-librespot[2004]: time="2026-01-27T09:49:00-06:00" level=trace msg="received accesspoint pong ack" Jan 27 09:49:00 volumio go-librespot[2004]: time="2026-01-27T09:49:00-06:00" level=trace msg="sent dealer ping" Jan 27 09:49:00 volumio go-librespot[2004]: time="2026-01-27T09:49:00-06:00" level=trace msg="received dealer pong" Jan 27 09:49:12 volumio volumio[1296]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Jan 27 09:49:12 volumio volumio[1296]: info: Preload queue cleared Jan 27 09:49:12 volumio volumio[1296]: info: Preloading song: http://streams.80s80s.de/mj/mp3-192/volumio Jan 27 09:49:12 volumio volumio[1296]: info: Preloading song: http://stream.radioparadise.com/beyond-flacm Jan 27 09:49:12 volumio volumio[1296]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm Jan 27 09:49:12 volumio volumio[1296]: info: Preloading song: https://stream.radioparadise.com/flacm Jan 27 09:49:12 volumio volumio[1296]: info: Exploding uri http://streams.80s80s.de/mj/mp3-192/volumio in service 80s80s Jan 27 09:49:12 volumio volumio[1296]: info: [1769528952531] [80s80s] explodeUri: http://streams.80s80s.de/mj/mp3-192/volumio Jan 27 09:49:12 volumio volumio[1296]: info: Exploding uri http://stream.radioparadise.com/beyond-flacm in service radio_paradise Jan 27 09:49:12 volumio volumio[1296]: error: [RadioParadise] explodeUri: No station found for uri "http://stream.radioparadise.com/beyond-flacm" Jan 27 09:49:12 volumio volumio[1296]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 09:49:12 volumio volumio[1296]: TypeError: Cannot read properties of undefined (reading 'then') Jan 27 09:49:12 volumio volumio[1296]: TypeError: Cannot read properties of undefined (reading 'then') Jan 27 09:49:12 volumio volumio[1296]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Jan 27 09:49:12 volumio volumio[1296]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jan 27 09:49:12 volumio volumio[1296]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jan 27 09:49:12 volumio volumio[1296]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Jan 27 09:49:12 volumio volumio[1296]: at listOnTimeout (node:internal/timers:573:17) Jan 27 09:49:12 volumio volumio[1296]: at process.processTimers (node:internal/timers:514:7) Jan 27 09:49:12 volumio volumio[1296]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 09:49:13 volumio sudo[11914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 09:48' Jan 27 09:49:13 volumio sudo[11914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"