Feb 21 20:56:17 volumio go-librespot[3278]: time="2026-02-21T20:56:17+01:00" level=trace msg="sent dealer ping" Feb 21 20:56:17 volumio go-librespot[3278]: time="2026-02-21T20:56:17+01:00" level=trace msg="received dealer pong" Feb 21 20:56:47 volumio go-librespot[3278]: time="2026-02-21T20:56:47+01:00" level=trace msg="sent dealer ping" Feb 21 20:56:47 volumio go-librespot[3278]: time="2026-02-21T20:56:47+01:00" level=trace msg="received dealer pong" Feb 21 20:56:58 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: webradio , addMyWebRadio Feb 21 20:56:58 volumio volumio[1222]: info: {"name":"chilizet","uri":"http://go-audio.toya.net.pl/63216"} Feb 21 20:56:58 volumio volumio[1222]: info: Saving local item my-web-radio Feb 21 20:56:59 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 21 20:56:59 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:00 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:00 volumio volumio[1222]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::ClearQueue Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::stop Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::stPlaybackTimer Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:00 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:00 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::serviceStop Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:00 volumio volumio[1222]: info: CoreCommandRouter::serviceStop Feb 21 20:57:00 volumio volumio[1222]: info: [1771703820978] ControllerWebradio::stop Feb 21 20:57:00 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::clearPlayQueue Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 21 20:57:00 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::addQueueItems Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::addQueueItems Feb 21 20:57:00 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:00 volumio volumio[1222]: info: Adding Item to queue: http://go-audio.toya.net.pl/63216 Feb 21 20:57:00 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 21 20:57:00 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 21 20:57:00 volumio volumio[1222]: info: CoreCommandRouter::volumioPlay Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::play index 0 Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::stop Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::play index undefined Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:00 volumio volumio[1222]: info: CoreStateMachine::startPlaybackTimer Feb 21 20:57:00 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:00 volumio volumio[1222]: info: [1771703820993] ControllerWebradio::clearAddPlayTrack Feb 21 20:57:00 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 20:57:00 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:00 volumio volumio[1222]: info: Feb 21 20:57:00 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:00 volumio volumio[1222]: info: sendMpdCommand stop took 20 milliseconds Feb 21 20:57:00 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:00 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:00 volumio volumio[1222]: info: sendMpdCommand stop took 5 milliseconds Feb 21 20:57:00 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 20:57:00 volumio volumio[1222]: info: Feb 21 20:57:00 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:00 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:00 volumio volumio[1222]: info: sendMpdCommand status took 1 milliseconds Feb 21 20:57:00 volumio volumio[1222]: info: sendMpdCommand clear took 1 milliseconds Feb 21 20:57:00 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:01 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:01 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand load "http://go-audio.toya.net.pl/63216" Feb 21 20:57:01 volumio volumio[1222]: info: Feb 21 20:57:01 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:01 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:01 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:01 volumio volumio[1222]: info: Feb 21 20:57:01 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:01 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:01 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:01 volumio volumio[1222]: error: updateQueue error: null Feb 21 20:57:01 volumio volumio[1222]: info: ------------------------------ 3ms Feb 21 20:57:01 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:01 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:01 volumio volumio[1222]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 21 20:57:01 volumio volumio[1222]: info: ------------------------------ 6ms Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand add "http://go-audio.toya.net.pl/63216" Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:02 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand status took 1055 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand status took 1055 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand add "http://go-audio.toya.net.pl/63216" took 1 milliseconds Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand play Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 21 20:57:02 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: No code Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 21 20:57:02 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: No code Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 1106ms Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 1106ms Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:02 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:02 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 56ms Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand play took 54 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 4ms Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 3ms Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:02 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:02 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 15ms Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 14ms Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand status took 16 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand status took 16 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand status took 15 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:02 volumio volumio[1222]: info: Feb 21 20:57:02 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand status took 4 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63216","artist":null,"album":null,"uri":"http://go-audio.toya.net.pl/63216","trackType":"pl/63216"} Feb 21 20:57:02 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63216","artist":null,"album":null,"uri":"http://go-audio.toya.net.pl/63216","trackType":"pl/63216"} Feb 21 20:57:02 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus play Feb 21 20:57:02 volumio volumio[1222]: info: Received an update from plugin. extracting info from payload Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63216","artist":null,"album":null,"uri":"http://go-audio.toya.net.pl/63216","trackType":"pl/63216"} Feb 21 20:57:02 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus play Feb 21 20:57:02 volumio volumio[1222]: info: Received an update from plugin. extracting info from payload Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 33ms Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 47ms Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 46ms Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand status took 28 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand status took 28 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63216","artist":null,"album":null,"uri":"http://go-audio.toya.net.pl/63216","trackType":"pl/63216"} Feb 21 20:57:02 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus play Feb 21 20:57:02 volumio volumio[1222]: info: Received an update from plugin. extracting info from payload Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 46ms Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 21 20:57:02 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:02 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63216","artist":null,"album":null,"uri":"http://go-audio.toya.net.pl/63216","trackType":"pl/63216"} Feb 21 20:57:02 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus play Feb 21 20:57:02 volumio volumio[1222]: info: Received an update from plugin. extracting info from payload Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63216","artist":null,"album":null,"uri":"http://go-audio.toya.net.pl/63216","trackType":"pl/63216"} Feb 21 20:57:02 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus play Feb 21 20:57:02 volumio volumio[1222]: info: Received an update from plugin. extracting info from payload Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:02 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 77ms Feb 21 20:57:02 volumio volumio[1222]: info: ------------------------------ 76ms Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:02 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:17 volumio go-librespot[3278]: time="2026-02-21T20:57:17+01:00" level=trace msg="received accesspoint ping" Feb 21 20:57:17 volumio go-librespot[3278]: time="2026-02-21T20:57:17+01:00" level=trace msg="received accesspoint pong ack" Feb 21 20:57:17 volumio go-librespot[3278]: time="2026-02-21T20:57:17+01:00" level=trace msg="sent dealer ping" Feb 21 20:57:17 volumio go-librespot[3278]: time="2026-02-21T20:57:17+01:00" level=trace msg="received dealer pong" Feb 21 20:57:32 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 21 20:57:32 volumio volumio[1222]: info: [1771703852779] [80s80s] handleBrowseUri curUri: 80s80s Feb 21 20:57:32 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:35 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::ClearQueue Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::stop Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::stPlaybackTimer Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::serviceStop Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::serviceStop Feb 21 20:57:35 volumio volumio[1222]: info: [1771703855019] ControllerWebradio::stop Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::clearPlayQueue Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::addQueueItems Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::addQueueItems Feb 21 20:57:35 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:35 volumio volumio[1222]: info: Adding Item to queue: 80s80s/nineties Feb 21 20:57:35 volumio volumio[1222]: info: Exploding uri 80s80s/nineties in service 80s80s Feb 21 20:57:35 volumio volumio[1222]: info: [1771703855021] [80s80s] explodeUri: 80s80s/nineties Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPlay Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::play index 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::stop Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::play index undefined Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:35 volumio volumio[1222]: info: Feb 21 20:57:35 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:35 volumio volumio[1222]: info: sendMpdCommand stop took 21 milliseconds Feb 21 20:57:35 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:35 volumio volumio[1222]: info: sendMpdCommand status took 1 milliseconds Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:35 volumio volumio[1222]: info: Feb 21 20:57:35 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:35 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:35 volumio volumio[1222]: info: Feb 21 20:57:35 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:35 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:35 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:35 volumio volumio[1222]: info: sendMpdCommand status took 1 milliseconds Feb 21 20:57:35 volumio volumio[1222]: info: sendMpdCommand status took 1 milliseconds Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:35 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63216","artist":null,"album":null,"uri":"http://go-audio.toya.net.pl/63216","trackType":"pl/63216"} Feb 21 20:57:35 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: No code Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: ------------------------------ 21ms Feb 21 20:57:35 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 21 20:57:35 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:35 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:35 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63216","artist":null,"album":null,"uri":"http://go-audio.toya.net.pl/63216","trackType":"pl/63216"} Feb 21 20:57:35 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: No code Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63216","artist":null,"album":null,"uri":"http://go-audio.toya.net.pl/63216","trackType":"pl/63216"} Feb 21 20:57:35 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: No code Feb 21 20:57:35 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:35 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:35 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:35 volumio volumio[1222]: info: ------------------------------ 53ms Feb 21 20:57:35 volumio volumio[1222]: info: ------------------------------ 54ms Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:35 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:40 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 21 20:57:40 volumio volumio[1222]: info: [1771703860420] [80s80s] handleBrowseUri curUri: 80s80s/nineties Feb 21 20:57:40 volumio volumio[1222]: info: [1771703860421] [80s80s] getRadioContent url: nineties Feb 21 20:57:40 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:40 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:40 volumio volumio[1222]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::ClearQueue Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::stop Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:40 volumio volumio[1222]: info: CorePlayQueue::clearPlayQueue Feb 21 20:57:40 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 21 20:57:40 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::addQueueItems Feb 21 20:57:40 volumio volumio[1222]: info: CorePlayQueue::addQueueItems Feb 21 20:57:40 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:40 volumio volumio[1222]: info: Adding Item to queue: webnineties/1 Feb 21 20:57:40 volumio volumio[1222]: info: Exploding uri webnineties/1 in service 80s80s Feb 21 20:57:40 volumio volumio[1222]: info: [1771703860749] [80s80s] explodeUri: webnineties/1 Feb 21 20:57:40 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 21 20:57:40 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 21 20:57:40 volumio volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 21 20:57:40 volumio volumio[1222]: info: CoreCommandRouter::volumioPlay Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::play index 0 Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::stop Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::play index undefined Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:40 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:40 volumio volumio[1222]: info: CoreStateMachine::startPlaybackTimer Feb 21 20:57:40 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:40 volumio volumio[1222]: info: [1771703860753] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/grunge/mp3-192/volumio Feb 21 20:57:40 volumio volumio[1222]: info: [1771703860754] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=253&count=2 Feb 21 20:57:41 volumio volumio[1222]: info: [1771703861008] [80s80s] received new event containing 2 songs. Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 20:57:41 volumio volumio[1222]: info: sendMpdCommand stop took 1 milliseconds Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 20:57:41 volumio volumio[1222]: info: sendMpdCommand clear took 1 milliseconds Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 21 20:57:41 volumio volumio[1222]: info: Feb 21 20:57:41 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:41 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:41 volumio volumio[1222]: info: Feb 21 20:57:41 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:41 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:41 volumio volumio[1222]: info: Feb 21 20:57:41 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:41 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:41 volumio volumio[1222]: info: Feb 21 20:57:41 volumio volumio[1222]: ---------------------------- MPD announces state update: options Feb 21 20:57:41 volumio volumio[1222]: error: updateQueue error: null Feb 21 20:57:41 volumio volumio[1222]: error: updateQueue error: null Feb 21 20:57:41 volumio volumio[1222]: info: sendMpdCommand consume 1 took 3 milliseconds Feb 21 20:57:41 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:41 volumio volumio[1222]: info: ------------------------------ 2ms Feb 21 20:57:41 volumio volumio[1222]: info: ------------------------------ 2ms Feb 21 20:57:41 volumio volumio[1222]: info: [1771703861015] [80s80s] adding url: http://streams.90s90s.de/grunge/mp3-192/volumio Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" Feb 21 20:57:41 volumio volumio[1222]: info: Feb 21 20:57:41 volumio volumio[1222]: ---------------------------- MPD announces state update: options Feb 21 20:57:41 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:41 volumio volumio[1222]: info: Feb 21 20:57:41 volumio volumio[1222]: ---------------------------- MPD announces state update: options Feb 21 20:57:41 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:41 volumio volumio[1222]: error: updateQueue error: null Feb 21 20:57:41 volumio volumio[1222]: info: Feb 21 20:57:41 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:41 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:41 volumio volumio[1222]: info: ------------------------------ 5ms Feb 21 20:57:41 volumio volumio[1222]: info: sendMpdCommand status took 4 milliseconds Feb 21 20:57:41 volumio volumio[1222]: info: sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" took 3 milliseconds Feb 21 20:57:41 volumio volumio[1222]: info: sendMpdCommand status took 2 milliseconds Feb 21 20:57:41 volumio volumio[1222]: info: sendMpdCommand status took 2 milliseconds Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand play Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:41 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:41 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:41 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:41 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:41 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:41 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:41 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:41 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:41 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:41 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:41 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:41 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:41 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:41 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:41 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:41 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:41 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:41 volumio volumio[1222]: info: ------------------------------ 14ms Feb 21 20:57:41 volumio volumio[1222]: info: ------------------------------ 16ms Feb 21 20:57:41 volumio volumio[1222]: info: ------------------------------ 16ms Feb 21 20:57:41 volumio volumio[1222]: info: Feb 21 20:57:41 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:41 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:41 volumio volumio[1222]: info: Feb 21 20:57:41 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:41 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:41 volumio volumio[1222]: info: ------------------------------ 16ms Feb 21 20:57:41 volumio volumio[1222]: info: sendMpdCommand play took 14 milliseconds Feb 21 20:57:41 volumio volumio[1222]: info: ------------------------------ 2ms Feb 21 20:57:41 volumio volumio[1222]: info: ------------------------------ 1ms Feb 21 20:57:41 volumio volumio[1222]: info: [1771703861035] [80s80s] Pushing the next song state: Soundgarden - Black Hole Sun Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:41 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:41 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/05/6e/c5/056ec58e-cfc3-47a4-031d-1dc415b23bbf/source/600x600bb.jpg","name":"Soundgarden - Black Hole Sun","title":"Black Hole Sun","artist":"90s90s Grunge","album":"","streaming":true,"disableUiControls":true,"duration":"245","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 21 20:57:41 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:41 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 21 20:57:41 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 21 20:57:41 volumio volumio[1222]: info: [1771703861037] [80s80s] PlayNextTrack API delay: 30 Feb 21 20:57:41 volumio volumio[1222]: info: [1771703861049] [80s80s] Setting timer to: 10963 milliseconds. Feb 21 20:57:41 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:41 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:41 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:41 volumio volumio[1222]: info: CorePlayQueue::getTrack 1 Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 15 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 14 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 15 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 1 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 19ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 18ms Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 2 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 1 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 20ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 7ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 5ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 4ms Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 3ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 2 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 7ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 6 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 6ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 5 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 5ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 5 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 4ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 4 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 3ms Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 11ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 5 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 13ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 12ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 11ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 9ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 9ms Feb 21 20:57:42 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::ClearQueue Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::stop Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::stPlaybackTimer Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::serviceStop Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::serviceStop Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::stop Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::clearPlayQueue Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::addQueueItems Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::addQueueItems Feb 21 20:57:42 volumio volumio[1222]: info: Preload queue cleared Feb 21 20:57:42 volumio volumio[1222]: info: Adding Item to queue: webnineties/1 Feb 21 20:57:42 volumio volumio[1222]: info: Using cached record of: webnineties/1 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPushQueue Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::saveQueue Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPlay Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::play index 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::stop Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::play index undefined Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::startPlaybackTimer Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: [1771703862307] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/grunge/mp3-192/volumio Feb 21 20:57:42 volumio volumio[1222]: info: [1771703862308] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=253&count=2 Feb 21 20:57:42 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand stop took 12 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/05/6e/c5/056ec58e-cfc3-47a4-031d-1dc415b23bbf/source/600x600bb.jpg","name":"Soundgarden - Black Hole Sun","title":"Black Hole Sun","artist":"90s90s Grunge","album":"","streaming":true,"disableUiControls":true,"duration":"245","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 21 20:57:42 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:42 volumio volumio[1222]: info: No code Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:42 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:42 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:42 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 17 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 7 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand status took 7 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 29ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 20ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 20ms Feb 21 20:57:42 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:42 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:42 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:42 volumio volumio[1222]: info: [1771703862447] [80s80s] received new event containing 2 songs. Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand stop took 0 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand clear took 1 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: error: updateQueue error: null Feb 21 20:57:42 volumio volumio[1222]: error: updateQueue error: null Feb 21 20:57:42 volumio volumio[1222]: error: updateQueue error: null Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 3ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand consume 1 took 3 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 2ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 1ms Feb 21 20:57:42 volumio volumio[1222]: info: [1771703862452] [80s80s] adding url: http://streams.90s90s.de/grunge/mp3-192/volumio Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand add "http://streams.90s90s.de/grunge/mp3-192/volumio" took 1 milliseconds Feb 21 20:57:42 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand play Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: Feb 21 20:57:42 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:42 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 4ms Feb 21 20:57:42 volumio volumio[1222]: info: sendMpdCommand play took 3 milliseconds Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 2ms Feb 21 20:57:42 volumio volumio[1222]: info: ------------------------------ 1ms Feb 21 20:57:42 volumio volumio[1222]: info: [1771703862457] [80s80s] Pushing the next song state: Soundgarden - Black Hole Sun Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/05/6e/c5/056ec58e-cfc3-47a4-031d-1dc415b23bbf/source/600x600bb.jpg","name":"Soundgarden - Black Hole Sun","title":"Black Hole Sun","artist":"90s90s Grunge","album":"","streaming":true,"disableUiControls":true,"duration":"245","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 21 20:57:42 volumio volumio[1222]: verbose: CURRENT POSITION 0 Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::syncState stateService play Feb 21 20:57:42 volumio volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 21 20:57:42 volumio volumio[1222]: info: [1771703862458] [80s80s] PlayNextTrack API delay: 30 Feb 21 20:57:42 volumio volumio[1222]: info: [1771703862460] [80s80s] Setting timer to: 9542 milliseconds. Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:42 volumio volumio[1222]: info: CorePlayQueue::getTrack 1 Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 10 milliseconds Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 11 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 11 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 1 milliseconds Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 15ms Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 3 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 1 milliseconds Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 18ms Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 17ms Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 7ms Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 8ms Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 6ms Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:43 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:43 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:43 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:43 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:43 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 5ms Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 5 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 4ms Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 4 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 3ms Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 2ms Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 1 milliseconds Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces system playlist update Feb 21 20:57:43 volumio volumio[1222]: info: Ignoring MPD Status Update Feb 21 20:57:43 volumio volumio[1222]: info: Feb 21 20:57:43 volumio volumio[1222]: ---------------------------- MPD announces state update: player Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::getState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand status Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 4ms Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 3 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 1ms Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand status took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseState Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 10ms Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 10ms Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 9ms Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 8ms Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: verbose: ControllerMpd::parseTrackInfo Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ControllerMpd::pushState Feb 21 20:57:43 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:43 volumio volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 21 20:57:43 volumio volumio[1222]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 8ms Feb 21 20:57:43 volumio volumio[1222]: info: ------------------------------ 5ms Feb 21 20:57:47 volumio go-librespot[3278]: time="2026-02-21T20:57:47+01:00" level=trace msg="sent dealer ping" Feb 21 20:57:47 volumio go-librespot[3278]: time="2026-02-21T20:57:47+01:00" level=trace msg="received dealer pong" Feb 21 20:57:48 volumio go-librespot[3278]: time="2026-02-21T20:57:48+01:00" level=debug msg="handling pause player command from 7d9e7bee98b8d419cea3b2cbec0a3438804d86ef" Feb 21 20:57:48 volumio go-librespot[3278]: time="2026-02-21T20:57:48+01:00" level=debug msg="pause track at 99082ms" Feb 21 20:57:48 volumio go-librespot[3278]: time="2026-02-21T20:57:48+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3378" Feb 21 20:57:48 volumio go-librespot[3278]: time="2026-02-21T20:57:48+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 21 20:57:48 volumio go-librespot[3278]: time="2026-02-21T20:57:48+01:00" level=debug msg="sending successful reply for dealer request" Feb 21 20:57:48 volumio go-librespot[3278]: time="2026-02-21T20:57:48+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 21 20:57:48 volumio go-librespot[3278]: time="2026-02-21T20:57:48+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 21 20:57:48 volumio go-librespot[3278]: time="2026-02-21T20:57:48+01:00" level=trace msg="emitting websocket event: paused" Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EVHGWrwldPRtj","uri":"spotify:track:0ofHAoxe9vBkTCp2UQIavz","play_origin":"playlist"}} Feb 21 20:57:48 volumio volumio[1222]: info: Spotify is playing in volatile mode Feb 21 20:57:48 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: UNSET VOLATILE Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: {"status":"stop","position":0,"title":"90s90s Grunge","albumart":"/albumart?sourceicon=music_service/80s80s/images/90s90s-grunge-cover.png","uri":"http://streams.90s90s.de/grunge/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":1000,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":88,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: PUSH STATE SPOTIFY Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: {"status":"pause","service":"spop","title":"Dreams - 2004 Remaster","artist":"Fleetwood Mac","album":"Rumours (Super Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e02e52a59a28efa4773dd2bfe1b","uri":"spotify:track:0ofHAoxe9vBkTCp2UQIavz","trackType":"spotify","seek":98118,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 21 20:57:48 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:48 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:48 volumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 20:57:48 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EVHGWrwldPRtj","uri":"spotify:track:0ofHAoxe9vBkTCp2UQIavz","play_origin":"playlist"}} Feb 21 20:57:48 volumio volumio[1222]: info: Spotify is playing in volatile mode Feb 21 20:57:48 volumio volumio[1222]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: UNSET VOLATILE Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: {"status":"stop","position":0,"title":"90s90s Grunge","albumart":"/albumart?sourceicon=music_service/80s80s/images/90s90s-grunge-cover.png","uri":"http://streams.90s90s.de/grunge/mp3-192/volumio","trackType":"80s80s Radio","seek":0,"duration":1000,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":88,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: PUSH STATE SPOTIFY Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: {"status":"pause","service":"spop","title":"Dreams - 2004 Remaster","artist":"Fleetwood Mac","album":"Rumours (Super Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e02e52a59a28efa4773dd2bfe1b","uri":"spotify:track:0ofHAoxe9vBkTCp2UQIavz","trackType":"spotify","seek":98118,"duration":257,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Feb 21 20:57:48 volumio volumio[1222]: info: CoreCommandRouter::servicePushState Feb 21 20:57:48 volumio volumio[1222]: info: CoreStateMachine::pushState Feb 21 20:57:48 volumio volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:48 volumio volumio[1222]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 21 20:57:52 volumio volumio[1222]: info: [1771703872002] [80s80s] Pushing the next song state Soundgarden - Black Hole Sun and getting next track. Feb 21 20:57:52 volumio volumio[1222]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 20:57:52 volumio volumio[1222]: TypeError: Cannot set properties of undefined (setting 'name') Feb 21 20:57:52 volumio volumio[1222]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Feb 21 20:57:52 volumio volumio[1222]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Feb 21 20:57:52 volumio volumio[1222]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Feb 21 20:57:52 volumio volumio[1222]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Feb 21 20:57:52 volumio volumio[1222]: at process.processImmediate (node:internal/timers:478:21) Feb 21 20:57:52 volumio volumio[1222]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 20:57:52 volumio sudo[5397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 20:56' Feb 21 20:57:52 volumio sudo[5397]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"