Jan 11 21:18:00 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jan 11 21:18:00 volumio volumio[3018]: info: [1768162680452] [80s80s] handleBrowseUri curUri: 80s80s/nineties Jan 11 21:18:00 volumio volumio[3018]: info: [1768162680452] [80s80s] getRadioContent url: nineties Jan 11 21:18:00 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:02 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::ClearQueue Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::stPlaybackTimer Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::serviceStop Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::serviceStop Jan 11 21:18:02 volumio volumio[3018]: info: ControllerMpd::stop Jan 11 21:18:02 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::clearPlayQueue Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::addQueueItems Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::addQueueItems Jan 11 21:18:02 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:02 volumio volumio[3018]: info: Adding Item to queue: webnineties/1 Jan 11 21:18:02 volumio volumio[3018]: info: Exploding uri webnineties/1 in service 80s80s Jan 11 21:18:02 volumio volumio[3018]: info: [1768162682777] [80s80s] explodeUri: webnineties/1 Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::volumioPlay Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::play index 0 Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::play index undefined Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::startPlaybackTimer Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: info: [1768162682801] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/grunge/mp3-192/volumio Jan 11 21:18:02 volumio volumio[3018]: info: [1768162682801] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=253&count=2 Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:02 volumio volumio[3018]: info: Jan 11 21:18:02 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:02 volumio volumio[3018]: info: sendMpdCommand stop took 48 milliseconds Jan 11 21:18:02 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:02 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music115/v4/08/81/79/088179a5-d560-40d6-97a6-86ec6129602d/source/600x600bb.jpg","name":"a-ha - The Living Daylights","title":"The Living Daylights","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"291","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:02 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::syncState stateService stop Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:02 volumio volumio[3018]: info: No code Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:02 volumio volumio[3018]: info: sendMpdCommand status took 50 milliseconds Jan 11 21:18:02 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:02 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:02 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:18:02 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:02 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:02 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:02 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:02 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:02 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:02 volumio volumio[3018]: info: ------------------------------ 69ms Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:02 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:03 volumio volumio[3018]: info: [1768162683003] [80s80s] received new event containing 2 songs. Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand stop took 1 milliseconds Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 21:18:03 volumio volumio[3018]: info: Jan 11 21:18:03 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:03 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand clear took 2 milliseconds Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 21:18:03 volumio volumio[3018]: error: updateQueue error: null Jan 11 21:18:03 volumio volumio[3018]: info: ------------------------------ 4ms Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand consume 1 took 3 milliseconds Jan 11 21:18:03 volumio volumio[3018]: info: [1768162683013] [80s80s] adding url: http://streams.90s90s.de/grunge/mp3-192/volumio Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" Jan 11 21:18:03 volumio volumio[3018]: info: Jan 11 21:18:03 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:03 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" took 2 milliseconds Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand play Jan 11 21:18:03 volumio volumio[3018]: info: ------------------------------ 10ms Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand play took 4 milliseconds Jan 11 21:18:03 volumio volumio[3018]: info: [1768162683026] [80s80s] Pushing the next song state: Pearl Jam - Alive Jan 11 21:18:03 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:03 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:03 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:03 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music115/v4/f7/ba/8b/f7ba8bcd-ce91-aba4-cb2d-141968d94433/source/600x600bb.jpg","name":"Pearl Jam - Alive","title":"Alive","artist":"90s90s Grunge","album":"","streaming":true,"disableUiControls":true,"duration":"335","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:03 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:03 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:18:03 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:03 volumio volumio[3018]: info: [1768162683031] [80s80s] PlayNextTrack API delay: 30 Jan 11 21:18:03 volumio volumio[3018]: info: [1768162683034] [80s80s] Setting timer to: 335000 milliseconds. Jan 11 21:18:03 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:03 volumio volumio[3018]: info: CorePlayQueue::getTrack 1 Jan 11 21:18:03 volumio volumio[3018]: info: Jan 11 21:18:03 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:03 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:03 volumio volumio[3018]: info: Jan 11 21:18:03 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand status took 10 milliseconds Jan 11 21:18:03 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand status took 3 milliseconds Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:03 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:03 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:03 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:03 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:03 volumio volumio[3018]: info: ------------------------------ 24ms Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:03 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:03 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:03 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:03 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:03 volumio volumio[3018]: info: ------------------------------ 18ms Jan 11 21:18:03 volumio volumio[3018]: info: Jan 11 21:18:03 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:03 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:03 volumio volumio[3018]: info: Jan 11 21:18:03 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:03 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:03 volumio volumio[3018]: info: ------------------------------ 16ms Jan 11 21:18:03 volumio volumio[3018]: info: sendMpdCommand status took 14 milliseconds Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:03 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:04 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:18:04 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:04 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:04 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:04 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:04 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:04 volumio volumio[3018]: info: ------------------------------ 25ms Jan 11 21:18:06 volumio go-librespot[6273]: time="2026-01-11T21:18:06+01:00" level=trace msg="sent dealer ping" Jan 11 21:18:06 volumio go-librespot[6273]: time="2026-01-11T21:18:06+01:00" level=trace msg="received dealer pong" Jan 11 21:18:10 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::ClearQueue Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::stPlaybackTimer Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::serviceStop Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::serviceStop Jan 11 21:18:10 volumio volumio[3018]: info: ControllerMpd::stop Jan 11 21:18:10 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::clearPlayQueue Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::addQueueItems Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::addQueueItems Jan 11 21:18:10 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:10 volumio volumio[3018]: info: Adding Item to queue: webnineties/0 Jan 11 21:18:10 volumio volumio[3018]: info: Exploding uri webnineties/0 in service 80s80s Jan 11 21:18:10 volumio volumio[3018]: info: [1768162690776] [80s80s] explodeUri: webnineties/0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::volumioPlay Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::play index 0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::play index undefined Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::startPlaybackTimer Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: info: [1768162690798] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/pop/mp3-192/volumio Jan 11 21:18:10 volumio volumio[3018]: info: [1768162690799] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=141&count=2 Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:10 volumio volumio[3018]: info: Jan 11 21:18:10 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:10 volumio volumio[3018]: info: sendMpdCommand stop took 45 milliseconds Jan 11 21:18:10 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:10 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music115/v4/f7/ba/8b/f7ba8bcd-ce91-aba4-cb2d-141968d94433/source/600x600bb.jpg","name":"Pearl Jam - Alive","title":"Alive","artist":"90s90s Grunge","album":"","streaming":true,"disableUiControls":true,"duration":"335","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:10 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::syncState stateService stop Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:10 volumio volumio[3018]: info: No code Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:10 volumio volumio[3018]: info: sendMpdCommand status took 51 milliseconds Jan 11 21:18:10 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:10 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:10 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:18:10 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:10 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:10 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:10 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:10 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:10 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:10 volumio volumio[3018]: info: ------------------------------ 72ms Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:10 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:11 volumio volumio[3018]: info: [1768162691005] [80s80s] received new event containing 2 songs. Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand stop took 2 milliseconds Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 21:18:11 volumio volumio[3018]: info: Jan 11 21:18:11 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:11 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand clear took 3 milliseconds Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 21:18:11 volumio volumio[3018]: error: updateQueue error: null Jan 11 21:18:11 volumio volumio[3018]: info: ------------------------------ 4ms Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand consume 1 took 3 milliseconds Jan 11 21:18:11 volumio volumio[3018]: info: [1768162691015] [80s80s] adding url: http://streams.90s90s.de/pop/mp3-192/volumio Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" Jan 11 21:18:11 volumio volumio[3018]: info: Jan 11 21:18:11 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:11 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" took 2 milliseconds Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand play Jan 11 21:18:11 volumio volumio[3018]: info: ------------------------------ 9ms Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand play took 4 milliseconds Jan 11 21:18:11 volumio volumio[3018]: info: [1768162691027] [80s80s] Pushing the next song state: Snoop Doggy Dogg - Who Am I (What's My Name)? Jan 11 21:18:11 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:11 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:11 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:11 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music6/v4/36/d1/28/36d12884-5dcf-79b2-1261-8788100d3846/source/600x600bb.jpg","name":"Snoop Doggy Dogg - Who Am I (What's My Name)?","title":"Who Am I (What's My Name)?","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"243","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:11 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:11 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:18:11 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:11 volumio volumio[3018]: info: [1768162691029] [80s80s] PlayNextTrack API delay: 30 Jan 11 21:18:11 volumio volumio[3018]: info: [1768162691031] [80s80s] Setting timer to: 243000 milliseconds. Jan 11 21:18:11 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:11 volumio volumio[3018]: info: CorePlayQueue::getTrack 1 Jan 11 21:18:11 volumio volumio[3018]: info: Jan 11 21:18:11 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:11 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand status took 6 milliseconds Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:11 volumio volumio[3018]: info: Jan 11 21:18:11 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:11 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:11 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:11 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:11 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:11 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:11 volumio volumio[3018]: info: ------------------------------ 14ms Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand status took 5 milliseconds Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:11 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:11 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:11 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:11 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:11 volumio volumio[3018]: info: ------------------------------ 12ms Jan 11 21:18:11 volumio volumio[3018]: info: Jan 11 21:18:11 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:11 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:11 volumio volumio[3018]: info: Jan 11 21:18:11 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:11 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:11 volumio volumio[3018]: info: ------------------------------ 5ms Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand status took 3 milliseconds Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:11 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:18:11 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:11 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:11 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:11 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:11 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:11 volumio volumio[3018]: info: ------------------------------ 11ms Jan 11 21:18:18 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::ClearQueue Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::stPlaybackTimer Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::serviceStop Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::serviceStop Jan 11 21:18:18 volumio volumio[3018]: info: ControllerMpd::stop Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::clearPlayQueue Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::addQueueItems Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::addQueueItems Jan 11 21:18:18 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:18 volumio volumio[3018]: info: Adding Item to queue: webnineties/2 Jan 11 21:18:18 volumio volumio[3018]: info: Exploding uri webnineties/2 in service 80s80s Jan 11 21:18:18 volumio volumio[3018]: info: [1768162698611] [80s80s] explodeUri: webnineties/2 Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::volumioPlay Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::play index 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::play index undefined Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::startPlaybackTimer Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: [1768162698641] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/eurodance/mp3-192/volumio Jan 11 21:18:18 volumio volumio[3018]: info: [1768162698642] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=188&count=2 Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:18 volumio volumio[3018]: info: Jan 11 21:18:18 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:18 volumio volumio[3018]: info: sendMpdCommand stop took 60 milliseconds Jan 11 21:18:18 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music6/v4/36/d1/28/36d12884-5dcf-79b2-1261-8788100d3846/source/600x600bb.jpg","name":"Snoop Doggy Dogg - Who Am I (What's My Name)?","title":"Who Am I (What's My Name)?","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"243","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:18 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::syncState stateService stop Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:18 volumio volumio[3018]: info: No code Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:18 volumio volumio[3018]: info: sendMpdCommand status took 59 milliseconds Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:18 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:18 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:18 volumio volumio[3018]: info: ------------------------------ 84ms Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:18 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:18 volumio volumio[3018]: info: [1768162698870] [80s80s] received new event containing 2 songs. Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:18 volumio volumio[3018]: info: sendMpdCommand stop took 2 milliseconds Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 21:18:18 volumio volumio[3018]: info: Jan 11 21:18:18 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:18 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:18 volumio volumio[3018]: info: sendMpdCommand clear took 3 milliseconds Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 21:18:18 volumio volumio[3018]: error: updateQueue error: null Jan 11 21:18:18 volumio volumio[3018]: info: ------------------------------ 6ms Jan 11 21:18:18 volumio volumio[3018]: info: sendMpdCommand consume 1 took 3 milliseconds Jan 11 21:18:18 volumio volumio[3018]: info: [1768162698883] [80s80s] adding url: http://streams.90s90s.de/eurodance/mp3-192/volumio Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" Jan 11 21:18:18 volumio volumio[3018]: info: Jan 11 21:18:18 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:18 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:18 volumio volumio[3018]: info: sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" took 4 milliseconds Jan 11 21:18:18 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand play Jan 11 21:18:18 volumio volumio[3018]: info: ------------------------------ 11ms Jan 11 21:18:18 volumio volumio[3018]: info: sendMpdCommand play took 4 milliseconds Jan 11 21:18:18 volumio volumio[3018]: info: [1768162698898] [80s80s] Pushing the next song state: Captain Hollywood Project - Over and Over Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/90/57/8c/90578ced-9ead-f6a4-4005-6757369e96a7/source/600x600bb.jpg","name":"Captain Hollywood Project - Over and Over","title":"Over and Over","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"215","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:18 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:18:18 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:18 volumio volumio[3018]: info: [1768162698901] [80s80s] PlayNextTrack API delay: 30 Jan 11 21:18:18 volumio volumio[3018]: info: [1768162698907] [80s80s] Setting timer to: 215000 milliseconds. Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:18 volumio volumio[3018]: info: CorePlayQueue::getTrack 1 Jan 11 21:18:19 volumio volumio[3018]: info: Jan 11 21:18:19 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:19 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:19 volumio volumio[3018]: info: sendMpdCommand status took 5 milliseconds Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:19 volumio volumio[3018]: info: Jan 11 21:18:19 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:19 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:19 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:19 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:19 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:19 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:19 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:19 volumio volumio[3018]: info: ------------------------------ 19ms Jan 11 21:18:19 volumio volumio[3018]: info: sendMpdCommand status took 10 milliseconds Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:19 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:19 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:19 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:19 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:19 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:19 volumio volumio[3018]: info: ------------------------------ 19ms Jan 11 21:18:19 volumio volumio[3018]: info: Jan 11 21:18:19 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:19 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:19 volumio volumio[3018]: info: Jan 11 21:18:19 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:19 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:19 volumio volumio[3018]: info: Jan 11 21:18:19 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:19 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:19 volumio volumio[3018]: info: Jan 11 21:18:19 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:19 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:19 volumio volumio[3018]: info: ------------------------------ 11ms Jan 11 21:18:19 volumio volumio[3018]: info: sendMpdCommand status took 9 milliseconds Jan 11 21:18:19 volumio volumio[3018]: info: ------------------------------ 9ms Jan 11 21:18:19 volumio volumio[3018]: info: sendMpdCommand status took 5 milliseconds Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:19 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 21:18:19 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:19 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:19 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:19 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:19 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:19 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:19 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:19 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:19 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:19 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:19 volumio volumio[3018]: info: ------------------------------ 28ms Jan 11 21:18:19 volumio volumio[3018]: info: ------------------------------ 23ms Jan 11 21:18:36 volumio go-librespot[6273]: time="2026-01-11T21:18:36+01:00" level=trace msg="sent dealer ping" Jan 11 21:18:36 volumio go-librespot[6273]: time="2026-01-11T21:18:36+01:00" level=trace msg="received dealer pong" Jan 11 21:18:37 volumio volumio[3018]: info: Jan 11 21:18:37 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:37 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:37 volumio volumio[3018]: info: Jan 11 21:18:37 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:37 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:37 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:37 volumio volumio[3018]: info: ------------------------------ 5ms Jan 11 21:18:37 volumio volumio[3018]: info: sendMpdCommand status took 3 milliseconds Jan 11 21:18:37 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:37 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:37 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 21:18:37 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:37 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:37 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:37 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:37 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:37 volumio volumio[3018]: info: ------------------------------ 10ms Jan 11 21:18:41 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::ClearQueue Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::stPlaybackTimer Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::serviceStop Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::serviceStop Jan 11 21:18:41 volumio volumio[3018]: info: ControllerMpd::stop Jan 11 21:18:41 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::clearPlayQueue Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::addQueueItems Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::addQueueItems Jan 11 21:18:41 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:41 volumio volumio[3018]: info: Adding Item to queue: webnineties/3 Jan 11 21:18:41 volumio volumio[3018]: info: Exploding uri webnineties/3 in service 80s80s Jan 11 21:18:41 volumio volumio[3018]: info: [1768162721887] [80s80s] explodeUri: webnineties/3 Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::volumioPlay Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::play index 0 Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::play index undefined Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::startPlaybackTimer Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:41 volumio volumio[3018]: info: [1768162721914] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/beat/mp3-192/volumio Jan 11 21:18:41 volumio volumio[3018]: info: [1768162721915] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=140&count=2 Jan 11 21:18:41 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:41 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:41 volumio volumio[3018]: info: Jan 11 21:18:41 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:41 volumio volumio[3018]: info: sendMpdCommand stop took 59 milliseconds Jan 11 21:18:41 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:41 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:41 volumio volumio[3018]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/90/57/8c/90578ced-9ead-f6a4-4005-6757369e96a7/source/600x600bb.jpg","name":"Captain Hollywood Project - Over and Over","title":"Over and Over","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"215","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:41 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::syncState stateService stop Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:41 volumio volumio[3018]: info: No code Jan 11 21:18:41 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:41 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:41 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:41 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:41 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:41 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:41 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:41 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:41 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:42 volumio volumio[3018]: info: sendMpdCommand status took 59 milliseconds Jan 11 21:18:42 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:42 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:42 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:18:42 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:42 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:42 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:42 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:42 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:42 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:42 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:42 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:42 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:42 volumio volumio[3018]: info: ------------------------------ 81ms Jan 11 21:18:42 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:42 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:42 volumio volumio[3018]: info: [1768162722139] [80s80s] received new event containing 2 songs. Jan 11 21:18:42 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:42 volumio volumio[3018]: info: sendMpdCommand stop took 1 milliseconds Jan 11 21:18:42 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 21:18:42 volumio volumio[3018]: info: Jan 11 21:18:42 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:42 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:42 volumio volumio[3018]: info: sendMpdCommand clear took 2 milliseconds Jan 11 21:18:42 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 21:18:42 volumio volumio[3018]: error: updateQueue error: null Jan 11 21:18:42 volumio volumio[3018]: info: ------------------------------ 4ms Jan 11 21:18:42 volumio volumio[3018]: info: sendMpdCommand consume 1 took 3 milliseconds Jan 11 21:18:42 volumio volumio[3018]: info: [1768162722148] [80s80s] adding url: http://streams.90s90s.de/beat/mp3-192/volumio Jan 11 21:18:42 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/beat/mp3-192/volumio" Jan 11 21:18:42 volumio volumio[3018]: info: Jan 11 21:18:42 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:42 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:42 volumio volumio[3018]: info: sendMpdCommand add "http://streams.90s90s.de/beat/mp3-192/volumio" took 2 milliseconds Jan 11 21:18:42 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand play Jan 11 21:18:42 volumio volumio[3018]: info: ------------------------------ 9ms Jan 11 21:18:42 volumio volumio[3018]: info: sendMpdCommand play took 3 milliseconds Jan 11 21:18:42 volumio volumio[3018]: info: [1768162722160] [80s80s] Pushing the next song state: Spike - Respect Jan 11 21:18:42 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:42 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:42 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:42 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music112/v4/19/f0/ea/19f0ea1f-f0ca-560c-f019-53cfdd24fa52/cover.jpg/600x600bb.jpg","name":"Spike - Respect","title":"Respect","artist":"90s90s Clubhits","album":"","streaming":true,"disableUiControls":true,"duration":"214","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:42 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:42 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:18:42 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:42 volumio volumio[3018]: info: [1768162722162] [80s80s] PlayNextTrack API delay: 30 Jan 11 21:18:42 volumio volumio[3018]: info: [1768162722164] [80s80s] Setting timer to: 214000 milliseconds. Jan 11 21:18:42 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:42 volumio volumio[3018]: info: CorePlayQueue::getTrack 1 Jan 11 21:18:43 volumio volumio[3018]: info: Jan 11 21:18:43 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:43 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:43 volumio volumio[3018]: info: Jan 11 21:18:43 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:43 volumio volumio[3018]: info: sendMpdCommand status took 6 milliseconds Jan 11 21:18:43 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:43 volumio volumio[3018]: info: sendMpdCommand status took 3 milliseconds Jan 11 21:18:43 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:43 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:43 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:43 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:43 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:43 volumio volumio[3018]: info: ------------------------------ 19ms Jan 11 21:18:43 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:43 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:43 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:43 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:43 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:43 volumio volumio[3018]: info: ------------------------------ 16ms Jan 11 21:18:43 volumio volumio[3018]: info: Jan 11 21:18:43 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:43 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:43 volumio volumio[3018]: info: Jan 11 21:18:43 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:43 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:43 volumio volumio[3018]: info: ------------------------------ 5ms Jan 11 21:18:43 volumio volumio[3018]: info: sendMpdCommand status took 3 milliseconds Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:43 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 21:18:43 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:43 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:43 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:43 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:43 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:43 volumio volumio[3018]: info: ------------------------------ 11ms Jan 11 21:18:46 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::ClearQueue Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::stPlaybackTimer Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::serviceStop Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::serviceStop Jan 11 21:18:46 volumio volumio[3018]: info: ControllerMpd::stop Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::clearPlayQueue Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::addQueueItems Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::addQueueItems Jan 11 21:18:46 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:18:46 volumio volumio[3018]: info: Adding Item to queue: webnineties/4 Jan 11 21:18:46 volumio volumio[3018]: info: Exploding uri webnineties/4 in service 80s80s Jan 11 21:18:46 volumio volumio[3018]: info: [1768162726595] [80s80s] explodeUri: webnineties/4 Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::volumioPlay Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::play index 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::play index undefined Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::startPlaybackTimer Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: [1768162726620] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/techno/mp3-192/volumio Jan 11 21:18:46 volumio volumio[3018]: info: [1768162726620] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=261&count=2 Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:46 volumio volumio[3018]: info: Jan 11 21:18:46 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:46 volumio volumio[3018]: info: sendMpdCommand stop took 47 milliseconds Jan 11 21:18:46 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music112/v4/19/f0/ea/19f0ea1f-f0ca-560c-f019-53cfdd24fa52/cover.jpg/600x600bb.jpg","name":"Spike - Respect","title":"Respect","artist":"90s90s Clubhits","album":"","streaming":true,"disableUiControls":true,"duration":"214","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:46 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::syncState stateService stop Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:46 volumio volumio[3018]: info: No code Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:46 volumio volumio[3018]: info: sendMpdCommand status took 61 milliseconds Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:46 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:46 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:46 volumio volumio[3018]: info: ------------------------------ 88ms Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:18:46 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:18:46 volumio volumio[3018]: info: [1768162726750] [80s80s] received new event containing 2 songs. Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:18:46 volumio volumio[3018]: info: sendMpdCommand stop took 5 milliseconds Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 21:18:46 volumio volumio[3018]: info: Jan 11 21:18:46 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:46 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:46 volumio volumio[3018]: info: sendMpdCommand clear took 7 milliseconds Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 21:18:46 volumio volumio[3018]: error: updateQueue error: null Jan 11 21:18:46 volumio volumio[3018]: info: ------------------------------ 7ms Jan 11 21:18:46 volumio volumio[3018]: info: sendMpdCommand consume 1 took 4 milliseconds Jan 11 21:18:46 volumio volumio[3018]: info: [1768162726770] [80s80s] adding url: http://streams.90s90s.de/techno/mp3-192/volumio Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/techno/mp3-192/volumio" Jan 11 21:18:46 volumio volumio[3018]: info: Jan 11 21:18:46 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:46 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:46 volumio volumio[3018]: info: sendMpdCommand add "http://streams.90s90s.de/techno/mp3-192/volumio" took 3 milliseconds Jan 11 21:18:46 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand play Jan 11 21:18:46 volumio volumio[3018]: info: ------------------------------ 12ms Jan 11 21:18:46 volumio volumio[3018]: info: sendMpdCommand play took 17 milliseconds Jan 11 21:18:46 volumio volumio[3018]: info: [1768162726799] [80s80s] Pushing the next song state: DJ Hell - Suicide Commando Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music127/v4/e0/15/9a/e0159a1c-f2b1-1e8c-2bb1-c7d0255b9965/source/600x600bb.jpg","name":"DJ Hell - Suicide Commando","title":"Suicide Commando","artist":"90s90s Techno","album":"","streaming":true,"disableUiControls":true,"duration":"320","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:18:46 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:18:46 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:18:46 volumio volumio[3018]: info: [1768162726803] [80s80s] PlayNextTrack API delay: 30 Jan 11 21:18:46 volumio volumio[3018]: info: [1768162726806] [80s80s] Setting timer to: 320000 milliseconds. Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:46 volumio volumio[3018]: info: CorePlayQueue::getTrack 1 Jan 11 21:18:47 volumio volumio[3018]: info: Jan 11 21:18:47 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:47 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:47 volumio volumio[3018]: info: Jan 11 21:18:47 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:47 volumio volumio[3018]: info: sendMpdCommand status took 7 milliseconds Jan 11 21:18:47 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:47 volumio volumio[3018]: info: sendMpdCommand status took 3 milliseconds Jan 11 21:18:47 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:47 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:47 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:47 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:47 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:47 volumio volumio[3018]: info: ------------------------------ 18ms Jan 11 21:18:47 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:47 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:47 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:47 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:47 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:47 volumio volumio[3018]: info: ------------------------------ 14ms Jan 11 21:18:47 volumio volumio[3018]: info: Jan 11 21:18:47 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:47 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:47 volumio volumio[3018]: info: Jan 11 21:18:47 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:47 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:47 volumio volumio[3018]: info: Jan 11 21:18:47 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:18:47 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:18:47 volumio volumio[3018]: info: Jan 11 21:18:47 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:18:47 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:18:47 volumio volumio[3018]: info: ------------------------------ 9ms Jan 11 21:18:47 volumio volumio[3018]: info: sendMpdCommand status took 7 milliseconds Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:47 volumio volumio[3018]: info: ------------------------------ 11ms Jan 11 21:18:47 volumio volumio[3018]: info: sendMpdCommand status took 8 milliseconds Jan 11 21:18:47 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:47 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:47 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:47 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:47 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:47 volumio volumio[3018]: info: ------------------------------ 21ms Jan 11 21:18:47 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 21:18:47 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:18:47 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:18:47 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:18:47 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:18:47 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:18:47 volumio volumio[3018]: info: ------------------------------ 22ms Jan 11 21:19:06 volumio go-librespot[6273]: time="2026-01-11T21:19:06+01:00" level=trace msg="sent dealer ping" Jan 11 21:19:06 volumio go-librespot[6273]: time="2026-01-11T21:19:06+01:00" level=trace msg="received dealer pong" Jan 11 21:19:06 volumio volumio[3018]: info: Jan 11 21:19:06 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:06 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:06 volumio volumio[3018]: info: Jan 11 21:19:06 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:06 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:06 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:06 volumio volumio[3018]: info: ------------------------------ 12ms Jan 11 21:19:06 volumio volumio[3018]: info: sendMpdCommand status took 10 milliseconds Jan 11 21:19:06 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:06 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:06 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 21:19:06 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:06 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:06 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:06 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:06 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:06 volumio volumio[3018]: info: ------------------------------ 18ms Jan 11 21:19:08 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::ClearQueue Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::stPlaybackTimer Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::serviceStop Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::serviceStop Jan 11 21:19:08 volumio volumio[3018]: info: ControllerMpd::stop Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::clearPlayQueue Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::addQueueItems Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::addQueueItems Jan 11 21:19:08 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:19:08 volumio volumio[3018]: info: Adding Item to queue: webnineties/7 Jan 11 21:19:08 volumio volumio[3018]: info: Exploding uri webnineties/7 in service 80s80s Jan 11 21:19:08 volumio volumio[3018]: info: [1768162748667] [80s80s] explodeUri: webnineties/7 Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::volumioPlay Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::play index 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::play index undefined Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::startPlaybackTimer Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: [1768162748690] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/hiphop/mp3-192/volumio Jan 11 21:19:08 volumio volumio[3018]: info: [1768162748690] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=265&count=2 Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:08 volumio volumio[3018]: info: Jan 11 21:19:08 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:08 volumio volumio[3018]: info: sendMpdCommand stop took 44 milliseconds Jan 11 21:19:08 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music127/v4/e0/15/9a/e0159a1c-f2b1-1e8c-2bb1-c7d0255b9965/source/600x600bb.jpg","name":"DJ Hell - Suicide Commando","title":"Suicide Commando","artist":"90s90s Techno","album":"","streaming":true,"disableUiControls":true,"duration":"320","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:19:08 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::syncState stateService stop Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:08 volumio volumio[3018]: info: No code Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:08 volumio volumio[3018]: info: sendMpdCommand status took 50 milliseconds Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:08 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:08 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:08 volumio volumio[3018]: info: ------------------------------ 72ms Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:08 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:08 volumio volumio[3018]: info: [1768162748901] [80s80s] received new event containing 2 songs. Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:19:08 volumio volumio[3018]: info: sendMpdCommand stop took 1 milliseconds Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 21:19:08 volumio volumio[3018]: info: Jan 11 21:19:08 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:08 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:08 volumio volumio[3018]: info: sendMpdCommand clear took 3 milliseconds Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 21:19:08 volumio volumio[3018]: error: updateQueue error: null Jan 11 21:19:08 volumio volumio[3018]: info: ------------------------------ 4ms Jan 11 21:19:08 volumio volumio[3018]: info: sendMpdCommand consume 1 took 5 milliseconds Jan 11 21:19:08 volumio volumio[3018]: info: [1768162748913] [80s80s] adding url: http://streams.90s90s.de/hiphop/mp3-192/volumio Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/hiphop/mp3-192/volumio" Jan 11 21:19:08 volumio volumio[3018]: info: Jan 11 21:19:08 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:08 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:08 volumio volumio[3018]: info: sendMpdCommand add "http://streams.90s90s.de/hiphop/mp3-192/volumio" took 3 milliseconds Jan 11 21:19:08 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand play Jan 11 21:19:08 volumio volumio[3018]: info: ------------------------------ 8ms Jan 11 21:19:08 volumio volumio[3018]: info: sendMpdCommand play took 8 milliseconds Jan 11 21:19:08 volumio volumio[3018]: info: [1768162748931] [80s80s] Pushing the next song state: Salt-N-Pepa - Shoop Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music49/v4/03/75/b2/0375b26e-1466-4483-c849-c2af4117b815/source/600x600bb.jpg","name":"Salt-N-Pepa - Shoop","title":"Shoop","artist":"90s90s HipHop & Rap","album":"","streaming":true,"disableUiControls":true,"duration":"217","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:19:08 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:19:08 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:19:08 volumio volumio[3018]: info: [1768162748933] [80s80s] PlayNextTrack API delay: 30 Jan 11 21:19:08 volumio volumio[3018]: info: [1768162748936] [80s80s] Setting timer to: 13067 milliseconds. Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:08 volumio volumio[3018]: info: CorePlayQueue::getTrack 1 Jan 11 21:19:09 volumio volumio[3018]: info: Jan 11 21:19:09 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:09 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:09 volumio volumio[3018]: info: sendMpdCommand status took 6 milliseconds Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:09 volumio volumio[3018]: info: Jan 11 21:19:09 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:09 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:09 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:09 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:09 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:09 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:09 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:09 volumio volumio[3018]: info: ------------------------------ 14ms Jan 11 21:19:09 volumio volumio[3018]: info: sendMpdCommand status took 4 milliseconds Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:09 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:09 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:09 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:09 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:09 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:09 volumio volumio[3018]: info: ------------------------------ 12ms Jan 11 21:19:09 volumio volumio[3018]: info: Jan 11 21:19:09 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:09 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:09 volumio volumio[3018]: info: Jan 11 21:19:09 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:09 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:09 volumio volumio[3018]: info: ------------------------------ 7ms Jan 11 21:19:09 volumio volumio[3018]: info: sendMpdCommand status took 5 milliseconds Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:09 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 21:19:09 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:09 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:09 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:09 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:09 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:09 volumio volumio[3018]: info: ------------------------------ 13ms Jan 11 21:19:16 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::ClearQueue Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::stPlaybackTimer Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::serviceStop Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::serviceStop Jan 11 21:19:16 volumio volumio[3018]: info: ControllerMpd::stop Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::clearPlayQueue Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::addQueueItems Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::addQueueItems Jan 11 21:19:16 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:19:16 volumio volumio[3018]: info: Adding Item to queue: webnineties/6 Jan 11 21:19:16 volumio volumio[3018]: info: Exploding uri webnineties/6 in service 80s80s Jan 11 21:19:16 volumio volumio[3018]: info: [1768162756610] [80s80s] explodeUri: webnineties/6 Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::volumioPlay Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::play index 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::play index undefined Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::startPlaybackTimer Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: [1768162756632] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/rnb/mp3-192/volumio Jan 11 21:19:16 volumio volumio[3018]: info: [1768162756633] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=266&count=2 Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:16 volumio volumio[3018]: info: Jan 11 21:19:16 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:16 volumio volumio[3018]: info: sendMpdCommand stop took 43 milliseconds Jan 11 21:19:16 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music49/v4/03/75/b2/0375b26e-1466-4483-c849-c2af4117b815/source/600x600bb.jpg","name":"Salt-N-Pepa - Shoop","title":"Shoop","artist":"90s90s HipHop & Rap","album":"","streaming":true,"disableUiControls":true,"duration":"217","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:19:16 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::syncState stateService stop Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:16 volumio volumio[3018]: info: No code Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:16 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:16 volumio volumio[3018]: info: sendMpdCommand status took 58 milliseconds Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:16 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:16 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:16 volumio volumio[3018]: info: ------------------------------ 80ms Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:16 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:16 volumio volumio[3018]: info: [1768162756847] [80s80s] received new event containing 2 songs. Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:19:16 volumio volumio[3018]: info: sendMpdCommand stop took 1 milliseconds Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 21:19:16 volumio volumio[3018]: info: Jan 11 21:19:16 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:16 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:16 volumio volumio[3018]: info: sendMpdCommand clear took 3 milliseconds Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 21:19:16 volumio volumio[3018]: error: updateQueue error: null Jan 11 21:19:16 volumio volumio[3018]: info: ------------------------------ 4ms Jan 11 21:19:16 volumio volumio[3018]: info: sendMpdCommand consume 1 took 3 milliseconds Jan 11 21:19:16 volumio volumio[3018]: info: [1768162756857] [80s80s] adding url: http://streams.90s90s.de/rnb/mp3-192/volumio Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/rnb/mp3-192/volumio" Jan 11 21:19:16 volumio volumio[3018]: info: Jan 11 21:19:16 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:16 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:16 volumio volumio[3018]: info: sendMpdCommand add "http://streams.90s90s.de/rnb/mp3-192/volumio" took 2 milliseconds Jan 11 21:19:16 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand play Jan 11 21:19:16 volumio volumio[3018]: info: ------------------------------ 12ms Jan 11 21:19:16 volumio volumio[3018]: info: sendMpdCommand play took 12 milliseconds Jan 11 21:19:16 volumio volumio[3018]: info: [1768162756880] [80s80s] Pushing the next song state: Destiny's Child - Bills, Bills, Bills Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music115/v4/50/91/fe/5091fe44-8588-4303-def0-2b269bfaa691/source/600x600bb.jpg","name":"Destiny's Child - Bills, Bills, Bills","title":"Bills, Bills, Bills","artist":"90s90s Black & RnB","album":"","streaming":true,"disableUiControls":true,"duration":"251","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 11 21:19:16 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:19:16 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:19:16 volumio volumio[3018]: info: [1768162756882] [80s80s] PlayNextTrack API delay: 30 Jan 11 21:19:16 volumio volumio[3018]: info: [1768162756884] [80s80s] Setting timer to: 251000 milliseconds. Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:16 volumio volumio[3018]: info: CorePlayQueue::getTrack 1 Jan 11 21:19:17 volumio volumio[3018]: info: Jan 11 21:19:17 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:17 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:17 volumio volumio[3018]: info: sendMpdCommand status took 6 milliseconds Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:17 volumio volumio[3018]: info: Jan 11 21:19:17 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:17 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:17 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:17 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:17 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:17 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:17 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:17 volumio volumio[3018]: info: ------------------------------ 18ms Jan 11 21:19:17 volumio volumio[3018]: info: sendMpdCommand status took 7 milliseconds Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:17 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:17 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:17 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:17 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:17 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:17 volumio volumio[3018]: info: ------------------------------ 14ms Jan 11 21:19:17 volumio volumio[3018]: info: Jan 11 21:19:17 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:17 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:17 volumio volumio[3018]: info: Jan 11 21:19:17 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:17 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:17 volumio volumio[3018]: info: Jan 11 21:19:17 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:17 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:17 volumio volumio[3018]: info: Jan 11 21:19:17 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:17 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:17 volumio volumio[3018]: info: ------------------------------ 9ms Jan 11 21:19:17 volumio volumio[3018]: info: sendMpdCommand status took 10 milliseconds Jan 11 21:19:17 volumio volumio[3018]: info: ------------------------------ 8ms Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:17 volumio volumio[3018]: info: sendMpdCommand status took 15 milliseconds Jan 11 21:19:17 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:17 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:17 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:17 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:17 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:17 volumio volumio[3018]: info: ------------------------------ 31ms Jan 11 21:19:17 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 21:19:17 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:17 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:17 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:17 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:17 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 11 21:19:17 volumio volumio[3018]: info: ------------------------------ 31ms Jan 11 21:19:18 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 11 21:19:18 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 11 21:19:18 volumio systemd[1]: setdatetime-helper.service: Consumed 1.763s CPU time. Jan 11 21:19:31 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: rad357pl , handleBrowseUri Jan 11 21:19:31 volumio volumio[3018]: info: [1768162771623] [rad357pl] handleBrowseUri curUri: rad357pl Jan 11 21:19:31 volumio volumio[3018]: info: [1768162771624] [rad357pl] getRadioContent url: rad357pl Jan 11 21:19:31 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:19:35 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::ClearQueue Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::stPlaybackTimer Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::serviceStop Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::serviceStop Jan 11 21:19:35 volumio volumio[3018]: info: ControllerMpd::stop Jan 11 21:19:35 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::clearPlayQueue Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::addQueueItems Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::addQueueItems Jan 11 21:19:35 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:19:35 volumio volumio[3018]: info: Adding Item to queue: webrns/0 Jan 11 21:19:35 volumio volumio[3018]: info: Using cached record of: webrns/0 Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::volumioPushQueue Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::saveQueue Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::updateTrackBlock Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::getTrackBlock Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::volumioPlay Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::play index 0 Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::stop Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::play index undefined Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::startPlaybackTimer Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:35 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 21:19:35 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:35 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:35 volumio volumio[3018]: info: Jan 11 21:19:35 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:35 volumio volumio[3018]: info: sendMpdCommand stop took 47 milliseconds Jan 11 21:19:35 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:35 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:35 volumio volumio[3018]: info: sendMpdCommand stop took 18 milliseconds Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:35 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:35 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:35 volumio volumio[3018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received 80s80s Jan 11 21:19:35 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 21:19:35 volumio volumio[3018]: info: FusionDsp - Volumio is not playing Jan 11 21:19:35 volumio volumio[3018]: info: FusionDsp - Clipped samples monitor stopped Jan 11 21:19:35 volumio volumio[3018]: info: Jan 11 21:19:35 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:35 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:35 volumio volumio[3018]: info: sendMpdCommand status took 28 milliseconds Jan 11 21:19:35 volumio volumio[3018]: info: sendMpdCommand clear took 18 milliseconds Jan 11 21:19:35 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:35 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:35 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Jan 11 21:19:35 volumio volumio[3018]: error: updateQueue error: null Jan 11 21:19:35 volumio volumio[3018]: info: Jan 11 21:19:35 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:35 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:35 volumio volumio[3018]: info: ------------------------------ 8ms Jan 11 21:19:35 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 21:19:35 volumio volumio[3018]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 5 milliseconds Jan 11 21:19:35 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:35 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand play Jan 11 21:19:35 volumio volumio[3018]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 11 21:19:35 volumio volumio[3018]: info: ------------------------------ 45ms Jan 11 21:19:35 volumio volumio[3018]: info: ------------------------------ 13ms Jan 11 21:19:35 volumio volumio[3018]: info: sendMpdCommand play took 5 milliseconds Jan 11 21:19:35 volumio volumio[3018]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 21:19:35 volumio go-librespot[6273]: time="2026-01-11T21:19:35+01:00" level=trace msg="received accesspoint ping" Jan 11 21:19:35 volumio go-librespot[6273]: time="2026-01-11T21:19:35+01:00" level=trace msg="received accesspoint pong ack" Jan 11 21:19:36 volumio volumio[3018]: info: Jan 11 21:19:36 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:36 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:36 volumio volumio[3018]: info: Jan 11 21:19:36 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:36 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:36 volumio volumio[3018]: info: sendMpdCommand status took 3 milliseconds Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:36 volumio volumio[3018]: info: sendMpdCommand status took 6 milliseconds Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:36 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:36 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:36 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:36 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:36 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Jan 11 21:19:36 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus stop Jan 11 21:19:36 volumio volumio[3018]: info: ------------------------------ 14ms Jan 11 21:19:36 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:36 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:36 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:36 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:36 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Jan 11 21:19:36 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus play Jan 11 21:19:36 volumio volumio[3018]: info: Received an update from plugin. extracting info from payload Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:36 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:36 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:36 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:36 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:36 volumio volumio[3018]: info: ------------------------------ 38ms Jan 11 21:19:36 volumio volumio[3018]: info: FusionDsp - Volumio is playing Jan 11 21:19:36 volumio volumio[3018]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jan 11 21:19:36 volumio volumio[3018]: info: FusionDsp - Volumio is playing Jan 11 21:19:36 volumio volumio[3018]: info: Jan 11 21:19:36 volumio volumio[3018]: ---------------------------- MPD announces system playlist update Jan 11 21:19:36 volumio volumio[3018]: info: Ignoring MPD Status Update Jan 11 21:19:36 volumio volumio[3018]: info: Jan 11 21:19:36 volumio volumio[3018]: ---------------------------- MPD announces state update: player Jan 11 21:19:36 volumio volumio[3018]: info: ControllerMpd::getState Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand status Jan 11 21:19:36 volumio volumio[3018]: info: ------------------------------ 7ms Jan 11 21:19:36 volumio volumio[3018]: info: sendMpdCommand status took 5 milliseconds Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::parseState Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 21:19:36 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:36 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:36 volumio volumio[3018]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 21:19:36 volumio volumio[3018]: verbose: ControllerMpd::parseTrackInfo Jan 11 21:19:36 volumio volumio[3018]: info: ControllerMpd::pushState Jan 11 21:19:36 volumio volumio[3018]: info: CoreCommandRouter::servicePushState Jan 11 21:19:36 volumio volumio[3018]: info: CorePlayQueue::getTrack 0 Jan 11 21:19:36 volumio go-librespot[6273]: time="2026-01-11T21:19:36+01:00" level=trace msg="sent dealer ping" Jan 11 21:19:36 volumio volumio[3018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lord Of The Lost - Sharp Edges","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Jan 11 21:19:36 volumio volumio[3018]: verbose: CURRENT POSITION 0 Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::syncState stateService play Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::syncState currentStatus play Jan 11 21:19:36 volumio volumio[3018]: info: Received an update from plugin. extracting info from payload Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:36 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 21:19:36 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:36 volumio volumio[3018]: info: CoreStateMachine::pushState Jan 11 21:19:36 volumio volumio[3018]: info: CoreCommandRouter::volumioPushState Jan 11 21:19:36 volumio volumio[3018]: info: ------------------------------ 51ms Jan 11 21:19:36 volumio volumio[3018]: info: FusionDsp - Volumio is playing Jan 11 21:19:36 volumio volumio[3018]: info: FusionDsp - Volumio is playing Jan 11 21:19:36 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:36 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:36 volumio go-librespot[6273]: time="2026-01-11T21:19:36+01:00" level=trace msg="received dealer pong" Jan 11 21:19:38 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:38 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:38 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:38 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:40 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:40 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:40 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:40 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:42 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:42 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:42 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:42 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:44 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:44 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:44 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:44 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:46 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:46 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:46 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:46 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:48 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:48 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:48 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:48 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:50 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:50 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:50 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:50 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:51 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 11 21:19:51 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:19:52 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:52 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:52 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:52 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:54 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:54 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:54 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:54 volumio volumio[3018]: error: FusionDsp - Monitor WebSocket error: [object Object] Jan 11 21:19:55 volumio volumio[3018]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Jan 11 21:19:55 volumio volumio[3018]: info: Preload queue cleared Jan 11 21:19:55 volumio volumio[3018]: info: Preloading song: bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fbest-of-2025%2Fthe-best-video-game-soundtracks-of-2025@mediaItemRef=t1512323667@track=1@artistUrl=https%3A%2F%2Fsupergiantgames.bandcamp.com@albumUrl=https%3A%2F%2Fsupergiantgames.bandcamp.com%2Falbum%2Fhades-ii-original-soundtrack Jan 11 21:19:55 volumio volumio[3018]: info: Preloading song: bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fbest-of-2025%2Fthe-best-electronic-music-of-2025@mediaItemRef=t2466342299@track=1@artistUrl=https%3A%2F%2Fsherelle.bandcamp.com@albumUrl=https%3A%2F%2Fsherelle.bandcamp.com%2Falbum%2Fwith-a-vengeance Jan 11 21:19:55 volumio volumio[3018]: info: Preloading song: http://stream.radioparadise.com/global-flacm Jan 11 21:19:55 volumio volumio[3018]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth/motherearth Jan 11 21:19:55 volumio volumio[3018]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_klassik/motherearth.klassik Jan 11 21:19:55 volumio volumio[3018]: info: Exploding uri bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fbest-of-2025%2Fthe-best-video-game-soundtracks-of-2025@mediaItemRef=t1512323667@track=1@artistUrl=https%3A%2F%2Fsupergiantgames.bandcamp.com@albumUrl=https%3A%2F%2Fsupergiantgames.bandcamp.com%2Falbum%2Fhades-ii-original-soundtrack in service bandcamp Jan 11 21:19:55 volumio volumio[3018]: info: [bandcamp-browse] explodeUri: bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fbest-of-2025%2Fthe-best-video-game-soundtracks-of-2025@mediaItemRef=t1512323667@track=1@artistUrl=https%3A%2F%2Fsupergiantgames.bandcamp.com@albumUrl=https%3A%2F%2Fsupergiantgames.bandcamp.com%2Falbum%2Fhades-ii-original-soundtrack Jan 11 21:19:56 volumio volumio[3018]: info: Exploding uri bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fbest-of-2025%2Fthe-best-electronic-music-of-2025@mediaItemRef=t2466342299@track=1@artistUrl=https%3A%2F%2Fsherelle.bandcamp.com@albumUrl=https%3A%2F%2Fsherelle.bandcamp.com%2Falbum%2Fwith-a-vengeance in service bandcamp Jan 11 21:19:56 volumio volumio[3018]: info: [bandcamp-browse] explodeUri: bandcamp/article@articleUrl=https%3A%2F%2Fdaily.bandcamp.com%2Fbest-of-2025%2Fthe-best-electronic-music-of-2025@mediaItemRef=t2466342299@track=1@artistUrl=https%3A%2F%2Fsherelle.bandcamp.com@albumUrl=https%3A%2F%2Fsherelle.bandcamp.com%2Falbum%2Fwith-a-vengeance Jan 11 21:19:56 volumio volumio[3018]: info: Exploding uri http://stream.radioparadise.com/global-flacm in service radio_paradise Jan 11 21:19:56 volumio volumio[3018]: error: [RadioParadise] explodeUri: No station found for uri "http://stream.radioparadise.com/global-flacm" Jan 11 21:19:56 volumio volumio[3018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 21:19:56 volumio volumio[3018]: TypeError: Cannot read properties of undefined (reading 'then') Jan 11 21:19:56 volumio volumio[3018]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Jan 11 21:19:56 volumio volumio[3018]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jan 11 21:19:56 volumio volumio[3018]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jan 11 21:19:56 volumio volumio[3018]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Jan 11 21:19:56 volumio volumio[3018]: at listOnTimeout (node:internal/timers:573:17) Jan 11 21:19:56 volumio volumio[3018]: at process.processTimers (node:internal/timers:514:7) Jan 11 21:19:56 volumio volumio[3018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 21:19:57 volumio sudo[20809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 21:18' Jan 11 21:19:57 volumio sudo[20809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"