-- 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"