-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-07-09 16:45:48 CEST. -- Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: Preload queue cleared Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::ClearQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::updateTrackBlock Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrackBlock Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stPlaybackTimer Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::serviceStop Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::serviceStop Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::stop Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::clearPlayQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::saveQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::addQueueItems Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::addQueueItems Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: Preload queue cleared Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: Adding Item to queue: http://iradio.fi:8000/klasupro-hi.mp3 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::saveQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::updateTrackBlock Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrackBlock Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPlay Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::play index 0 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::play index undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::startPlaybackTimer Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: [1720536245520] ControllerWebradio::clearAddPlayTrack Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand stop took 15 milliseconds Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand stop took 2 milliseconds Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand clear took 16 milliseconds Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand load "http://iradio.fi:8000/klasupro-hi.mp3" Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 100 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: Preload queue cleared Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::ClearQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::clearPlayQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::saveQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::addQueueItems Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::addQueueItems Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: Preload queue cleared Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: Adding Item to queue: http://iradio.fi:8000/klasupro-hi.mp3 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::saveQueue Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::updateTrackBlock Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrackBlock Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPlay Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::play index 0 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::play index undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::startPlaybackTimer Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: info: [1720536245786] ControllerWebradio::clearAddPlayTrack Jul 09 16:44:05 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: error: updateQueue error: null Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 625ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand add "http://iradio.fi:8000/klasupro-hi.mp3" Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand stop took 377 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand add "http://iradio.fi:8000/klasupro-hi.mp3" took 3 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand clear took 3 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand play Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand load "http://iradio.fi:8000/klasupro-hi.mp3" Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: error: updateQueue error: null Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: error: updateQueue error: null Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 5ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 5ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand play took 3 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand add "http://iradio.fi:8000/klasupro-hi.mp3" Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand add "http://iradio.fi:8000/klasupro-hi.mp3" took 2 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand play Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 3ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand play took 2 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces state update: player Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::getState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces state update: player Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand status took 29 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::getState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand status took 1 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::servicePushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: 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":"klasupro-hi.mp3","artist":null,"album":null,"uri":"http://iradio.fi:8000/klasupro-hi.mp3","trackType":"mp3"} Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: CURRENT POSITION 0 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState stateService play Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState currentStatus stop Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 40ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::servicePushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: 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":"klasupro-hi.mp3","artist":null,"album":null,"uri":"http://iradio.fi:8000/klasupro-hi.mp3","trackType":"mp3"} Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: CURRENT POSITION 0 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState stateService play Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState currentStatus play Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Received an update from plugin. extracting info from payload Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 25ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces state update: player Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::getState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces state update: player Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::getState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 18ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand status took 14 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 17ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand status took 14 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::servicePushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":" Bizet Georges: 'Carmen' L'amour est un oiseau rebelle - Chor und Orchester der Bayerischen Staatsoper Janos Kulka ","artist":"Rondo Classic KlasuPro-hi","album":null,"uri":"http://iradio.fi:8000/klasupro-hi.mp3","trackType":"mp3"} Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: CURRENT POSITION 0 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState stateService play Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState currentStatus play Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Received an update from plugin. extracting info from payload Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 42ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::servicePushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":" Bizet Georges: 'Carmen' L'amour est un oiseau rebelle - Chor und Orchester der Bayerischen Staatsoper Janos Kulka ","artist":"Rondo Classic KlasuPro-hi","album":null,"uri":"http://iradio.fi:8000/klasupro-hi.mp3","trackType":"mp3"} Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: verbose: CURRENT POSITION 0 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState stateService play Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState currentStatus play Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: Received an update from plugin. extracting info from payload Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 41ms Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 100 Jul 09 16:44:06 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 09 16:44:19 volumio-rpi4-2gb volumio[2712]: info: VolumeController::SetAlsaVolume50 Jul 09 16:44:19 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:19 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:44:19 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:19 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 09 16:44:19 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:19 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 50 Jul 09 16:44:19 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 09 16:44:19 volumio-rpi4-2gb volumio[2712]: info: Setting Spotify Volume from Volumio: 50 Jul 09 16:44:21 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Jul 09 16:44:21 volumio-rpi4-2gb volumio[2712]: info: Sending Spotify command with payload to local API: /player/volume Jul 09 16:44:21 volumio-rpi4-2gb go-librespot[2921]: time="2024-07-09T16:44:21+02:00" level=debug msg="update volume to 32767/65535" Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioStop Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stPlaybackTimer Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::updateTrackBlock Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrackBlock Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::serviceStop Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::serviceStop Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: [1720536264750] ControllerWebradio::stop Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces state update: player Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand stop took 49 milliseconds Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::getState Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand status took 1 milliseconds Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseState Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::pushState Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::servicePushState Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 19ms Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 09 16:44:24 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 09 16:44:27 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioStop Jul 09 16:44:27 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:27 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:29 volumio-rpi4-2gb go-librespot[2921]: time="2024-07-09T16:44:29+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 09 16:44:29 volumio-rpi4-2gb go-librespot[2921]: time="2024-07-09T16:44:29+02:00" level=trace msg="emitting websocket event: volume" Jul 09 16:44:29 volumio-rpi4-2gb volumio[2712]: SPOTIFY: received: {"type":"volume","data":{"value":50,"max":100}} Jul 09 16:44:29 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED SPOTIFY VOLUME 50 Jul 09 16:44:29 volumio-rpi4-2gb volumio[2712]: verbose: New Socket.io Connection to 192.168.178.50:3000 from 192.168.178.68 UA: node-XMLHttpRequest Total Clients: 10 Jul 09 16:44:29 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 09 16:44:29 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioGetState Jul 09 16:44:29 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 09 16:44:32 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioGetState Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: Restoring Previous Volume level: 50 false true Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: VolumeController::SetAlsaVolume100 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: Volume configurations have been set Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: Disabling external Volume Control Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 50 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 100 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: info: Setting Spotify Volume from Volumio: 100 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 100 Jul 09 16:44:38 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 09 16:44:40 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jul 09 16:44:40 volumio-rpi4-2gb volumio[2712]: info: Sending Spotify command with payload to local API: /player/volume Jul 09 16:44:40 volumio-rpi4-2gb go-librespot[2921]: time="2024-07-09T16:44:40+02:00" level=debug msg="update volume to 65535/65535" Jul 09 16:44:40 volumio-rpi4-2gb go-librespot[2921]: time="2024-07-09T16:44:40+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 09 16:44:40 volumio-rpi4-2gb go-librespot[2921]: time="2024-07-09T16:44:40+02:00" level=trace msg="emitting websocket event: volume" Jul 09 16:44:40 volumio-rpi4-2gb volumio[2712]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jul 09 16:44:40 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: info: VolumeController::SetAlsaVolume50 Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SPOTIFY VOLUME 100 Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: SPOTIFY: VOLUMIO VOLUME 50 Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 09 16:44:43 volumio-rpi4-2gb volumio[2712]: info: Setting Spotify Volume from Volumio: 50 Jul 09 16:44:44 volumio-rpi4-2gb volumio[2712]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Jul 09 16:44:44 volumio-rpi4-2gb volumio[2712]: info: Sending Spotify command with payload to local API: /player/volume Jul 09 16:44:44 volumio-rpi4-2gb go-librespot[2921]: time="2024-07-09T16:44:44+02:00" level=debug msg="update volume to 32767/65535" Jul 09 16:44:44 volumio-rpi4-2gb go-librespot[2921]: time="2024-07-09T16:44:44+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 09 16:44:44 volumio-rpi4-2gb go-librespot[2921]: time="2024-07-09T16:44:44+02:00" level=trace msg="emitting websocket event: volume" Jul 09 16:44:44 volumio-rpi4-2gb volumio[2712]: SPOTIFY: received: {"type":"volume","data":{"value":50,"max":100}} Jul 09 16:44:44 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED SPOTIFY VOLUME 50 Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: Preload queue cleared Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::ClearQueue Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::clearPlayQueue Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::saveQueue Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::addQueueItems Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::addQueueItems Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: Preload queue cleared Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s290621 Jul 09 16:44:54 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: Preload queue cleared Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::ClearQueue Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::clearPlayQueue Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::saveQueue Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::addQueueItems Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::addQueueItems Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: Preload queue cleared Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s290621 Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::saveQueue Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::saveQueue Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::updateTrackBlock Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrackBlock Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::updateTrackBlock Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrackBlock Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPlay Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::play index 0 Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPlay Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::play index 0 Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::stop Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::play index undefined Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::play index undefined Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::startPlaybackTimer Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: [1720536295060] ControllerWebradio::clearAddPlayTrack Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::startPlaybackTimer Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: [1720536295061] ControllerWebradio::clearAddPlayTrack Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand stop took 9 milliseconds Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand stop took 7 milliseconds Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand clear took 3 milliseconds Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand clear took 3 milliseconds Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand load "http://channels.fluxfm.de/jazz-schwarzenstein/tunein/stream.mp3" Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand load "http://channels.fluxfm.de/jazz-schwarzenstein/tunein/stream.mp3" Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: error: updateQueue error: null Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: error: updateQueue error: null Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 5ms Jul 09 16:44:55 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 5ms Jul 09 16:44:58 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand add "http://channels.fluxfm.de/jazz-schwarzenstein/tunein/stream.mp3" Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand add "http://channels.fluxfm.de/jazz-schwarzenstein/tunein/stream.mp3" Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand add "http://channels.fluxfm.de/jazz-schwarzenstein/tunein/stream.mp3" took 1134 milliseconds Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand add "http://channels.fluxfm.de/jazz-schwarzenstein/tunein/stream.mp3" took 2 milliseconds Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand play Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand play Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 5ms Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 5ms Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand play took 3 milliseconds Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand play took 2 milliseconds Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces state update: player Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::getState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces state update: player Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand status took 21 milliseconds Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::getState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand status took 1 milliseconds Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::pushState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::servicePushState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: 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":"stream.mp3","artist":null,"album":null,"uri":"http://channels.fluxfm.de/jazz-schwarzenstein/tunein/stream.mp3","trackType":"mp3"} Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: CURRENT POSITION 0 Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState stateService play Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState currentStatus stop Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 29ms Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::pushState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::servicePushState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: 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":"stream.mp3","artist":null,"album":null,"uri":"http://channels.fluxfm.de/jazz-schwarzenstein/tunein/stream.mp3","trackType":"mp3"} Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: verbose: CURRENT POSITION 0 Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState stateService play Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState currentStatus play Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: Received an update from plugin. extracting info from payload Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 16ms Jul 09 16:44:59 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces system playlist update Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: Ignoring MPD Status Update Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: ---------------------------- MPD announces state update: player Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::getState Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 5ms Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand status took 2 milliseconds Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseState Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: ControllerMpd::pushState Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::servicePushState Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: CorePlayQueue::getTrack 0 Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Julian & Roman Wasserfuhr with Jörg Brinkmann - Tears","artist":null,"album":null,"uri":"http://channels.fluxfm.de/jazz-schwarzenstein/tunein/stream.mp3","trackType":"mp3"} Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: verbose: CURRENT POSITION 0 Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState stateService play Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::syncState currentStatus play Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: Received an update from plugin. extracting info from payload Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: CoreStateMachine::pushState Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioPushState Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: info: ------------------------------ 17ms Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 09 16:45:00 volumio-rpi4-2gb volumio[2712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 09 16:45:21 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 16:45:21 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 09 16:45:21 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 09 16:45:21 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 09 16:45:27 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 16:45:27 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 09 16:45:29 volumio-rpi4-2gb wpa_supplicant[739]: RRM: Ignoring radio measurement request: Not RRM network Jul 09 16:45:34 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesRadio Paradise Jul 09 16:45:34 volumio-rpi4-2gb volumio[2712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 16:45:34 volumio-rpi4-2gb volumio[2712]: Cannot find translation for source Bandcamp Discover Jul 09 16:45:34 volumio-rpi4-2gb volumio[2712]: Cannot find translation for source YouTube Music Jul 09 16:45:34 volumio-rpi4-2gb volumio[2712]: Cannot find translation for source Spotify Jul 09 16:45:34 volumio-rpi4-2gb volumio[2712]: info: Disabling plugin radio_paradise Jul 09 16:45:34 volumio-rpi4-2gb volumio[2712]: info: Done. Jul 09 16:45:37 volumio-rpi4-2gb wpa_supplicant[739]: RRM: Ignoring radio measurement request: Not RRM network Jul 09 16:45:43 volumio-rpi4-2gb wpa_supplicant[739]: RRM: Ignoring radio measurement request: Not RRM network Jul 09 16:45:47 volumio-rpi4-2gb volumio[2712]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 16:45:47 volumio-rpi4-2gb volumio[2712]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Jul 09 16:45:47 volumio-rpi4-2gb volumio[2712]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 09 16:45:47 volumio-rpi4-2gb volumio[2712]: errno: -3007, Jul 09 16:45:47 volumio-rpi4-2gb volumio[2712]: code: 'ENOTFOUND', Jul 09 16:45:47 volumio-rpi4-2gb volumio[2712]: syscall: 'getaddrinfo', Jul 09 16:45:47 volumio-rpi4-2gb volumio[2712]: hostname: 'lastfm.freetls.fastly.net' Jul 09 16:45:47 volumio-rpi4-2gb volumio[2712]: } Jul 09 16:45:47 volumio-rpi4-2gb volumio[2712]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 16:45:48 volumio-rpi4-2gb sudo[3414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-09 16:44 Jul 09 16:45:48 volumio-rpi4-2gb sudo[3414]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"