-- Logs begin at Thu 2019-02-14 13:12:00 +03, end at Sun 2024-07-14 00:56:19 +03. -- Jul 14 00:55:01 volumio volumio[930]: info: Jul 14 00:55:01 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:01 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:01 volumio volumio[930]: info: sendMpdCommand status took 7 milliseconds Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:01 volumio volumio[930]: info: Jul 14 00:55:01 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:01 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:01 volumio volumio[930]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 14 00:55:01 volumio volumio[930]: info: sendMpdCommand status took 2 milliseconds Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:01 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:01 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:01 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:01 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:01 volumio volumio[930]: info: ------------------------------ 15ms Jul 14 00:55:01 volumio volumio[930]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:01 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:01 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:01 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:01 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:01 volumio volumio[930]: info: ------------------------------ 8ms Jul 14 00:55:01 volumio volumio[930]: info: Jul 14 00:55:01 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:01 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:01 volumio volumio[930]: info: Jul 14 00:55:01 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:01 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:01 volumio volumio[930]: info: Jul 14 00:55:01 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:01 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:01 volumio volumio[930]: info: ------------------------------ 3ms Jul 14 00:55:01 volumio volumio[930]: info: ------------------------------ 9ms Jul 14 00:55:01 volumio volumio[930]: info: sendMpdCommand status took 7 milliseconds Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:01 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:01 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:01 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:01 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:01 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:01 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:01 volumio volumio[930]: info: ------------------------------ 12ms Jul 14 00:55:02 volumio volumio[930]: info: Jul 14 00:55:02 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:02 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:02 volumio volumio[930]: info: Jul 14 00:55:02 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:02 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:02 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:02 volumio volumio[930]: info: ------------------------------ 3ms Jul 14 00:55:02 volumio volumio[930]: info: sendMpdCommand status took 2 milliseconds Jul 14 00:55:02 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:02 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:02 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:02 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:02 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:02 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:02 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:02 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:02 volumio volumio[930]: info: ------------------------------ 7ms Jul 14 00:55:05 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jul 14 00:55:05 volumio volumio[930]: info: [1720907705860] [80s80s] handleBrowseUri curUri: 80s80s/eighties Jul 14 00:55:05 volumio volumio[930]: info: [1720907705860] [80s80s] getRadioContent url: eighties Jul 14 00:55:05 volumio volumio[930]: info: Preload queue cleared Jul 14 00:55:20 volumio volumio[930]: info: Preload queue cleared Jul 14 00:55:20 volumio volumio[930]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::ClearQueue Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::stop Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::stPlaybackTimer Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::updateTrackBlock Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::getTrackBlock Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:20 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:20 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::serviceStop Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:20 volumio volumio[930]: info: CoreCommandRouter::serviceStop Jul 14 00:55:20 volumio volumio[930]: info: ControllerMpd::stop Jul 14 00:55:20 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::clearPlayQueue Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::saveQueue Jul 14 00:55:20 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::addQueueItems Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::addQueueItems Jul 14 00:55:20 volumio volumio[930]: info: Preload queue cleared Jul 14 00:55:20 volumio volumio[930]: info: Adding Item to queue: webeighties/8 Jul 14 00:55:20 volumio volumio[930]: info: Exploding uri webeighties/8 in service 80s80s Jul 14 00:55:20 volumio volumio[930]: info: [1720907720975] [80s80s] explodeUri: webeighties/8 Jul 14 00:55:20 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::saveQueue Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::updateTrackBlock Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::getTrackBlock Jul 14 00:55:20 volumio volumio[930]: info: CoreCommandRouter::volumioPlay Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::play index 0 Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::stop Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::play index undefined Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:20 volumio volumio[930]: info: CoreStateMachine::startPlaybackTimer Jul 14 00:55:20 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:20 volumio volumio[930]: info: [1720907720992] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/bowie/mp3-192/volumio Jul 14 00:55:20 volumio volumio[930]: info: [1720907720992] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=84&count=2 Jul 14 00:55:20 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:20 volumio volumio[930]: info: Jul 14 00:55:20 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:20 volumio volumio[930]: info: sendMpdCommand stop took 27 milliseconds Jul 14 00:55:21 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:21 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:21 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:21 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:21 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:21 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:21 volumio volumio[930]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/54/ea/49/54ea499d-f334-75c0-7644-b15fedd0f752/source/600x600bb.jpg","name":"Robbie Williams - Freedom","title":"Freedom","artist":"90s90s Boygroups","album":"","streaming":true,"disableUiControls":true,"duration":"246","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 14 00:55:21 volumio volumio[930]: verbose: CURRENT POSITION 0 Jul 14 00:55:21 volumio volumio[930]: info: CoreStateMachine::syncState stateService stop Jul 14 00:55:21 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus stop Jul 14 00:55:21 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:21 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:21 volumio volumio[930]: info: No code Jul 14 00:55:21 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:21 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:21 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:21 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:21 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:21 volumio volumio[930]: info: sendMpdCommand status took 22 milliseconds Jul 14 00:55:21 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:21 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:21 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:21 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:21 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:21 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:21 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:21 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:21 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:21 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:21 volumio volumio[930]: info: ------------------------------ 37ms Jul 14 00:55:21 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:21 volumio volumio[930]: info: [1720907721296] [80s80s] received new event containing 2 songs. Jul 14 00:55:21 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 00:55:21 volumio volumio[930]: info: sendMpdCommand stop took 2 milliseconds Jul 14 00:55:21 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand clear Jul 14 00:55:21 volumio volumio[930]: info: Jul 14 00:55:21 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:21 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:21 volumio volumio[930]: info: sendMpdCommand clear took 2 milliseconds Jul 14 00:55:21 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 14 00:55:21 volumio volumio[930]: error: updateQueue error: null Jul 14 00:55:21 volumio volumio[930]: info: ------------------------------ 3ms Jul 14 00:55:21 volumio volumio[930]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 14 00:55:21 volumio volumio[930]: info: [1720907721302] [80s80s] adding url: http://streams.80s80s.de/bowie/mp3-192/volumio Jul 14 00:55:21 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/bowie/mp3-192/volumio" Jul 14 00:55:21 volumio volumio[930]: info: Jul 14 00:55:21 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:21 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:21 volumio volumio[930]: info: sendMpdCommand add "http://streams.80s80s.de/bowie/mp3-192/volumio" took 1 milliseconds Jul 14 00:55:21 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand play Jul 14 00:55:21 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:21 volumio volumio[930]: info: sendMpdCommand play took 4 milliseconds Jul 14 00:55:21 volumio volumio[930]: info: [1720907721311] [80s80s] Pushing the next song state: David Bowie - Diamond Dogs Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:21 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:21 volumio volumio[930]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/8c/16/e7/8c16e71f-5a5c-797c-efcf-f0550d576892/source/600x600bb.jpg","name":"David Bowie - Diamond Dogs","title":"Diamond Dogs","artist":"80s80s David Bowie","album":"","streaming":true,"disableUiControls":true,"duration":"362","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 14 00:55:21 volumio volumio[930]: verbose: CURRENT POSITION 0 Jul 14 00:55:21 volumio volumio[930]: info: CoreStateMachine::syncState stateService play Jul 14 00:55:21 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus stop Jul 14 00:55:21 volumio volumio[930]: info: [1720907721313] [80s80s] PlayNextTrack API delay: 30 Jul 14 00:55:21 volumio volumio[930]: info: [1720907721314] [80s80s] Setting timer to: 362000 milliseconds. Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:21 volumio volumio[930]: info: CorePlayQueue::getTrack 1 Jul 14 00:55:23 volumio volumio[930]: info: Jul 14 00:55:23 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:23 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:23 volumio volumio[930]: info: Jul 14 00:55:23 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:23 volumio volumio[930]: info: sendMpdCommand status took 8 milliseconds Jul 14 00:55:23 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:23 volumio volumio[930]: info: sendMpdCommand status took 2 milliseconds Jul 14 00:55:23 volumio volumio[930]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:23 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:23 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:23 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:23 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:23 volumio volumio[930]: info: ------------------------------ 16ms Jul 14 00:55:23 volumio volumio[930]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:23 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:23 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:23 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:23 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:23 volumio volumio[930]: info: ------------------------------ 9ms Jul 14 00:55:23 volumio volumio[930]: info: Jul 14 00:55:23 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:23 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:23 volumio volumio[930]: info: Jul 14 00:55:23 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:23 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:23 volumio volumio[930]: info: Jul 14 00:55:23 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:23 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:23 volumio volumio[930]: info: Jul 14 00:55:23 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:23 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:23 volumio volumio[930]: info: ------------------------------ 4ms Jul 14 00:55:23 volumio volumio[930]: info: sendMpdCommand status took 4 milliseconds Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:23 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:23 volumio volumio[930]: info: sendMpdCommand status took 4 milliseconds Jul 14 00:55:23 volumio volumio[930]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:23 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:23 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:23 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:23 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:23 volumio volumio[930]: info: ------------------------------ 11ms Jul 14 00:55:23 volumio volumio[930]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 14 00:55:23 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:23 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:23 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:23 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:23 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:23 volumio volumio[930]: info: ------------------------------ 11ms Jul 14 00:55:24 volumio volumio[930]: info: Jul 14 00:55:24 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:24 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:24 volumio volumio[930]: info: Jul 14 00:55:24 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:24 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:24 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:24 volumio volumio[930]: info: ------------------------------ 3ms Jul 14 00:55:24 volumio volumio[930]: info: sendMpdCommand status took 1 milliseconds Jul 14 00:55:24 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:24 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:24 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:24 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:24 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:24 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:24 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:24 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:24 volumio volumio[930]: info: ------------------------------ 7ms Jul 14 00:55:28 volumio volumio[930]: info: Preload queue cleared Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::ClearQueue Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::stop Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::stPlaybackTimer Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::updateTrackBlock Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrackBlock Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::serviceStop Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::serviceStop Jul 14 00:55:28 volumio volumio[930]: info: ControllerMpd::stop Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::clearPlayQueue Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::saveQueue Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::addQueueItems Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::addQueueItems Jul 14 00:55:28 volumio volumio[930]: info: Preload queue cleared Jul 14 00:55:28 volumio volumio[930]: info: Adding Item to queue: webeighties/7 Jul 14 00:55:28 volumio volumio[930]: info: Exploding uri webeighties/7 in service 80s80s Jul 14 00:55:28 volumio volumio[930]: info: [1720907728631] [80s80s] explodeUri: webeighties/7 Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::saveQueue Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::updateTrackBlock Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrackBlock Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::volumioPlay Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::play index 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::stop Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::play index undefined Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::startPlaybackTimer Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: [1720907728644] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/mj/mp3-192/volumio Jul 14 00:55:28 volumio volumio[930]: info: [1720907728645] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=156&count=2 Jul 14 00:55:28 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:28 volumio volumio[930]: info: Jul 14 00:55:28 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:28 volumio volumio[930]: info: sendMpdCommand stop took 22 milliseconds Jul 14 00:55:28 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music125/v4/8c/16/e7/8c16e71f-5a5c-797c-efcf-f0550d576892/source/600x600bb.jpg","name":"David Bowie - Diamond Dogs","title":"Diamond Dogs","artist":"80s80s David Bowie","album":"","streaming":true,"disableUiControls":true,"duration":"362","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 14 00:55:28 volumio volumio[930]: verbose: CURRENT POSITION 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::syncState stateService stop Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus stop Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:28 volumio volumio[930]: info: No code Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:28 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:28 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:28 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:28 volumio volumio[930]: info: sendMpdCommand status took 25 milliseconds Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:28 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:28 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:28 volumio volumio[930]: info: ------------------------------ 36ms Jul 14 00:55:28 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:28 volumio volumio[930]: info: [1720907728900] [80s80s] received new event containing 2 songs. Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 00:55:28 volumio volumio[930]: info: sendMpdCommand stop took 1 milliseconds Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand clear Jul 14 00:55:28 volumio volumio[930]: info: Jul 14 00:55:28 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:28 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:28 volumio volumio[930]: info: sendMpdCommand clear took 2 milliseconds Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 14 00:55:28 volumio volumio[930]: error: updateQueue error: null Jul 14 00:55:28 volumio volumio[930]: info: ------------------------------ 2ms Jul 14 00:55:28 volumio volumio[930]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 14 00:55:28 volumio volumio[930]: info: [1720907728906] [80s80s] adding url: http://streams.80s80s.de/mj/mp3-192/volumio Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/mj/mp3-192/volumio" Jul 14 00:55:28 volumio volumio[930]: info: Jul 14 00:55:28 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:28 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:28 volumio volumio[930]: info: sendMpdCommand add "http://streams.80s80s.de/mj/mp3-192/volumio" took 2 milliseconds Jul 14 00:55:28 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand play Jul 14 00:55:28 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:28 volumio volumio[930]: info: sendMpdCommand play took 1 milliseconds Jul 14 00:55:28 volumio volumio[930]: info: [1720907728913] [80s80s] Pushing the next song state: Michael Jackson - A Place with No Name (Original Version) Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/b4/59/c9/b459c9f4-16b4-0b8b-94e1-275b79634776/source/600x600bb.jpg","name":"Michael Jackson - A Place with No Name (Original Version)","title":"A Place with No Name (Original Version)","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"287","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 14 00:55:28 volumio volumio[930]: verbose: CURRENT POSITION 0 Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::syncState stateService play Jul 14 00:55:28 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus stop Jul 14 00:55:28 volumio volumio[930]: info: [1720907728914] [80s80s] PlayNextTrack API delay: 30 Jul 14 00:55:28 volumio volumio[930]: info: [1720907728916] [80s80s] Setting timer to: 287000 milliseconds. Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:28 volumio volumio[930]: info: CorePlayQueue::getTrack 1 Jul 14 00:55:29 volumio volumio[930]: info: Jul 14 00:55:29 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:29 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:29 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:29 volumio volumio[930]: info: Jul 14 00:55:29 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:29 volumio volumio[930]: info: sendMpdCommand status took 9 milliseconds Jul 14 00:55:29 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:29 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:29 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:29 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:29 volumio volumio[930]: info: sendMpdCommand status took 2 milliseconds Jul 14 00:55:29 volumio volumio[930]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 14 00:55:29 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:29 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:29 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:29 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:29 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:29 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:29 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:29 volumio volumio[930]: info: ------------------------------ 19ms Jul 14 00:55:29 volumio volumio[930]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 14 00:55:29 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:29 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:29 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:29 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:29 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:29 volumio volumio[930]: info: ------------------------------ 13ms Jul 14 00:55:30 volumio volumio[930]: info: Jul 14 00:55:30 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:30 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:30 volumio volumio[930]: info: Jul 14 00:55:30 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:30 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:30 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:30 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:30 volumio volumio[930]: info: sendMpdCommand status took 2 milliseconds Jul 14 00:55:30 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:30 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:30 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:30 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:30 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:30 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:30 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:30 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:30 volumio volumio[930]: info: ------------------------------ 12ms Jul 14 00:55:32 volumio volumio[930]: info: Jul 14 00:55:32 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:32 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:32 volumio volumio[930]: info: Jul 14 00:55:32 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:32 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:32 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:32 volumio volumio[930]: info: ------------------------------ 4ms Jul 14 00:55:32 volumio volumio[930]: info: sendMpdCommand status took 2 milliseconds Jul 14 00:55:32 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:32 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:32 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:32 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:32 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:32 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:32 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:32 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:32 volumio volumio[930]: info: ------------------------------ 9ms Jul 14 00:55:37 volumio volumio[930]: info: Preload queue cleared Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::ClearQueue Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::stop Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::stPlaybackTimer Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::updateTrackBlock Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrackBlock Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::serviceStop Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::serviceStop Jul 14 00:55:37 volumio volumio[930]: info: ControllerMpd::stop Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::clearPlayQueue Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::saveQueue Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::addQueueItems Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::addQueueItems Jul 14 00:55:37 volumio volumio[930]: info: Preload queue cleared Jul 14 00:55:37 volumio volumio[930]: info: Adding Item to queue: webeighties/6 Jul 14 00:55:37 volumio volumio[930]: info: Exploding uri webeighties/6 in service 80s80s Jul 14 00:55:37 volumio volumio[930]: info: [1720907737669] [80s80s] explodeUri: webeighties/6 Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::saveQueue Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::updateTrackBlock Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrackBlock Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::volumioPlay Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::play index 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::stop Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::play index undefined Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::startPlaybackTimer Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: [1720907737682] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/dm/mp3-192/volumio Jul 14 00:55:37 volumio volumio[930]: info: [1720907737682] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=87&count=2 Jul 14 00:55:37 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:37 volumio volumio[930]: info: Jul 14 00:55:37 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:37 volumio volumio[930]: info: sendMpdCommand stop took 22 milliseconds Jul 14 00:55:37 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/b4/59/c9/b459c9f4-16b4-0b8b-94e1-275b79634776/source/600x600bb.jpg","name":"Michael Jackson - A Place with No Name (Original Version)","title":"A Place with No Name (Original Version)","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"287","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 14 00:55:37 volumio volumio[930]: verbose: CURRENT POSITION 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::syncState stateService stop Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus stop Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:37 volumio volumio[930]: info: No code Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:37 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:37 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:37 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:37 volumio volumio[930]: info: sendMpdCommand status took 26 milliseconds Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:37 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:37 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:37 volumio volumio[930]: info: ------------------------------ 37ms Jul 14 00:55:37 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:37 volumio volumio[930]: info: [1720907737930] [80s80s] received new event containing 2 songs. Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 00:55:37 volumio volumio[930]: info: sendMpdCommand stop took 2 milliseconds Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand clear Jul 14 00:55:37 volumio volumio[930]: info: Jul 14 00:55:37 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:37 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:37 volumio volumio[930]: info: sendMpdCommand clear took 2 milliseconds Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 14 00:55:37 volumio volumio[930]: error: updateQueue error: null Jul 14 00:55:37 volumio volumio[930]: info: ------------------------------ 3ms Jul 14 00:55:37 volumio volumio[930]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 14 00:55:37 volumio volumio[930]: info: [1720907737936] [80s80s] adding url: http://streams.80s80s.de/dm/mp3-192/volumio Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/dm/mp3-192/volumio" Jul 14 00:55:37 volumio volumio[930]: info: Jul 14 00:55:37 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:37 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:37 volumio volumio[930]: info: sendMpdCommand add "http://streams.80s80s.de/dm/mp3-192/volumio" took 4 milliseconds Jul 14 00:55:37 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand play Jul 14 00:55:37 volumio volumio[930]: info: ------------------------------ 6ms Jul 14 00:55:37 volumio volumio[930]: info: sendMpdCommand play took 2 milliseconds Jul 14 00:55:37 volumio volumio[930]: info: [1720907737947] [80s80s] Pushing the next song state: Depeche Mode - Precious Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:37 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:37 volumio volumio[930]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music/v4/ba/a2/34/baa23498-551c-d5b9-7405-ca147b564837/source/600x600bb.jpg","name":"Depeche Mode - Precious","title":"Precious","artist":"80s80s Depeche Mode","album":"","streaming":true,"disableUiControls":true,"duration":"233","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 14 00:55:37 volumio volumio[930]: verbose: CURRENT POSITION 0 Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::syncState stateService play Jul 14 00:55:37 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus stop Jul 14 00:55:37 volumio volumio[930]: info: [1720907737948] [80s80s] PlayNextTrack API delay: 30 Jul 14 00:55:37 volumio volumio[930]: info: [1720907737949] [80s80s] Setting timer to: 233000 milliseconds. Jul 14 00:55:38 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:38 volumio volumio[930]: info: CorePlayQueue::getTrack 1 Jul 14 00:55:39 volumio volumio[930]: info: Jul 14 00:55:39 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:39 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:39 volumio volumio[930]: info: sendMpdCommand status took 5 milliseconds Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:39 volumio volumio[930]: info: Jul 14 00:55:39 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:39 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:39 volumio volumio[930]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:39 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:39 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:39 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:39 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:39 volumio volumio[930]: info: ------------------------------ 10ms Jul 14 00:55:39 volumio volumio[930]: info: sendMpdCommand status took 3 milliseconds Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:39 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:39 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:39 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:39 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:39 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:39 volumio volumio[930]: info: ------------------------------ 7ms Jul 14 00:55:39 volumio volumio[930]: info: Jul 14 00:55:39 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:39 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:39 volumio volumio[930]: info: Jul 14 00:55:39 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:39 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:39 volumio volumio[930]: info: Jul 14 00:55:39 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:39 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:39 volumio volumio[930]: info: Jul 14 00:55:39 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:39 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:39 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:39 volumio volumio[930]: info: sendMpdCommand status took 3 milliseconds Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:39 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:39 volumio volumio[930]: info: sendMpdCommand status took 3 milliseconds Jul 14 00:55:39 volumio volumio[930]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:39 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:39 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:39 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:39 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:39 volumio volumio[930]: info: ------------------------------ 10ms Jul 14 00:55:39 volumio volumio[930]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 14 00:55:39 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:39 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:39 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:39 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:39 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:39 volumio volumio[930]: info: ------------------------------ 9ms Jul 14 00:55:49 volumio volumio[930]: info: Preload queue cleared Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::ClearQueue Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::stop Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::stPlaybackTimer Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::updateTrackBlock Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrackBlock Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::serviceStop Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::serviceStop Jul 14 00:55:49 volumio volumio[930]: info: ControllerMpd::stop Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::clearPlayQueue Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::saveQueue Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::addQueueItems Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::addQueueItems Jul 14 00:55:49 volumio volumio[930]: info: Preload queue cleared Jul 14 00:55:49 volumio volumio[930]: info: Adding Item to queue: webeighties/2 Jul 14 00:55:49 volumio volumio[930]: info: Exploding uri webeighties/2 in service 80s80s Jul 14 00:55:49 volumio volumio[930]: info: [1720907749539] [80s80s] explodeUri: webeighties/2 Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::saveQueue Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::updateTrackBlock Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrackBlock Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::volumioPlay Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::play index 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::stop Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::play index undefined Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::startPlaybackTimer Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: [1720907749553] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Jul 14 00:55:49 volumio volumio[930]: info: [1720907749553] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Jul 14 00:55:49 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:49 volumio volumio[930]: info: Jul 14 00:55:49 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:49 volumio volumio[930]: info: sendMpdCommand stop took 24 milliseconds Jul 14 00:55:49 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music/v4/ba/a2/34/baa23498-551c-d5b9-7405-ca147b564837/source/600x600bb.jpg","name":"Depeche Mode - Precious","title":"Precious","artist":"80s80s Depeche Mode","album":"","streaming":true,"disableUiControls":true,"duration":"233","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 14 00:55:49 volumio volumio[930]: verbose: CURRENT POSITION 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::syncState stateService stop Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus stop Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:49 volumio volumio[930]: info: No code Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:49 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:49 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:49 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:49 volumio volumio[930]: info: sendMpdCommand status took 22 milliseconds Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:49 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:49 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::pushState Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::volumioPushState Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:49 volumio volumio[930]: info: ------------------------------ 34ms Jul 14 00:55:49 volumio volumio[930]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 14 00:55:49 volumio volumio[930]: info: [1720907749808] [80s80s] received new event containing 2 songs. Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 00:55:49 volumio volumio[930]: info: sendMpdCommand stop took 1 milliseconds Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand clear Jul 14 00:55:49 volumio volumio[930]: info: Jul 14 00:55:49 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:49 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:49 volumio volumio[930]: info: sendMpdCommand clear took 2 milliseconds Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 14 00:55:49 volumio volumio[930]: error: updateQueue error: null Jul 14 00:55:49 volumio volumio[930]: info: ------------------------------ 3ms Jul 14 00:55:49 volumio volumio[930]: info: sendMpdCommand consume 1 took 1 milliseconds Jul 14 00:55:49 volumio volumio[930]: info: [1720907749814] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Jul 14 00:55:49 volumio volumio[930]: info: Jul 14 00:55:49 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:49 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:49 volumio volumio[930]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 2 milliseconds Jul 14 00:55:49 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand play Jul 14 00:55:49 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:49 volumio volumio[930]: info: sendMpdCommand play took 3 milliseconds Jul 14 00:55:49 volumio volumio[930]: info: [1720907749823] [80s80s] Pushing the next song state: Breathe - How Can I Fall? Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music114/v4/8d/e5/47/8de54742-5bc4-883c-ed7b-281efe76ec2f/source/600x600bb.jpg","name":"Breathe - How Can I Fall?","title":"How Can I Fall?","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"221","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 14 00:55:49 volumio volumio[930]: verbose: CURRENT POSITION 0 Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::syncState stateService play Jul 14 00:55:49 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus stop Jul 14 00:55:49 volumio volumio[930]: info: [1720907749825] [80s80s] PlayNextTrack API delay: 30 Jul 14 00:55:49 volumio volumio[930]: info: [1720907749826] [80s80s] Setting timer to: 221000 milliseconds. Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:49 volumio volumio[930]: info: CorePlayQueue::getTrack 1 Jul 14 00:55:50 volumio volumio[930]: info: Jul 14 00:55:50 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:50 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:50 volumio volumio[930]: info: Jul 14 00:55:50 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:50 volumio volumio[930]: info: sendMpdCommand status took 7 milliseconds Jul 14 00:55:50 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:50 volumio volumio[930]: info: sendMpdCommand status took 2 milliseconds Jul 14 00:55:50 volumio volumio[930]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:50 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:50 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:50 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:50 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:50 volumio volumio[930]: info: ------------------------------ 13ms Jul 14 00:55:50 volumio volumio[930]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:50 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:50 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:50 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:50 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:50 volumio volumio[930]: info: ------------------------------ 11ms Jul 14 00:55:50 volumio volumio[930]: info: Jul 14 00:55:50 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:50 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:50 volumio volumio[930]: info: Jul 14 00:55:50 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:50 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:50 volumio volumio[930]: info: Jul 14 00:55:50 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:50 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:50 volumio volumio[930]: info: Jul 14 00:55:50 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:50 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:50 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:50 volumio volumio[930]: info: sendMpdCommand status took 4 milliseconds Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:50 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:50 volumio volumio[930]: info: sendMpdCommand status took 4 milliseconds Jul 14 00:55:50 volumio volumio[930]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:50 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:50 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:50 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:50 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:50 volumio volumio[930]: info: ------------------------------ 12ms Jul 14 00:55:50 volumio volumio[930]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 14 00:55:50 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:50 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:50 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:50 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:50 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:50 volumio volumio[930]: info: ------------------------------ 10ms Jul 14 00:55:54 volumio volumio[930]: info: Jul 14 00:55:54 volumio volumio[930]: ---------------------------- MPD announces system playlist update Jul 14 00:55:54 volumio volumio[930]: info: Ignoring MPD Status Update Jul 14 00:55:54 volumio volumio[930]: info: Jul 14 00:55:54 volumio volumio[930]: ---------------------------- MPD announces state update: player Jul 14 00:55:54 volumio volumio[930]: info: ControllerMpd::getState Jul 14 00:55:54 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status Jul 14 00:55:54 volumio volumio[930]: info: ------------------------------ 5ms Jul 14 00:55:54 volumio volumio[930]: info: sendMpdCommand status took 2 milliseconds Jul 14 00:55:54 volumio volumio[930]: verbose: ControllerMpd::parseState Jul 14 00:55:54 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 00:55:54 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 14 00:55:54 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo Jul 14 00:55:54 volumio volumio[930]: info: ControllerMpd::pushState Jul 14 00:55:54 volumio volumio[930]: info: CoreCommandRouter::servicePushState Jul 14 00:55:54 volumio volumio[930]: info: CorePlayQueue::getTrack 0 Jul 14 00:55:54 volumio volumio[930]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 14 00:55:54 volumio volumio[930]: info: ------------------------------ 8ms Jul 14 00:56:03 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 14 00:56:03 volumio volumio[930]: info: Preload queue cleared Jul 14 00:56:06 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 14 00:56:06 volumio volumio[930]: info: Preload queue cleared Jul 14 00:56:15 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 14 00:56:15 volumio volumio[930]: info: Preload queue cleared Jul 14 00:56:18 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 14 00:56:18 volumio volumio[930]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 00:56:18 volumio volumio[930]: TypeError: Cannot read property 'length' of undefined Jul 14 00:56:18 volumio volumio[930]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 14 00:56:18 volumio volumio[930]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 14 00:56:18 volumio volumio[930]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 14 00:56:18 volumio volumio[930]: at Parser.emit (events.js:315:20) Jul 14 00:56:18 volumio volumio[930]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 14 00:56:18 volumio volumio[930]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 14 00:56:18 volumio volumio[930]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 14 00:56:18 volumio volumio[930]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 14 00:56:18 volumio volumio[930]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 14 00:56:18 volumio volumio[930]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 14 00:56:18 volumio volumio[930]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 14 00:56:18 volumio volumio[930]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 14 00:56:18 volumio volumio[930]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 14 00:56:18 volumio volumio[930]: at IncomingMessage.emit (events.js:327:22) Jul 14 00:56:18 volumio volumio[930]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 14 00:56:18 volumio volumio[930]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 14 00:56:18 volumio volumio[930]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 00:56:19 volumio sudo[6766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-14 00:55 Jul 14 00:56:19 volumio sudo[6766]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"