Jan 14 09:22:11 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::ClearQueue Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::stPlaybackTimer Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::serviceStop Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::serviceStop Jan 14 09:22:11 volumio volumio[1239]: info: [1768368131666] ControllerWebradio::stop Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::clearPlayQueue Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::addQueueItems Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::addQueueItems Jan 14 09:22:11 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:22:11 volumio volumio[1239]: info: Adding Item to queue: https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::volumioPlay Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::play index 0 Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::play index undefined Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:11 volumio volumio[1239]: info: [1768368131714] ControllerWebradio::clearAddPlayTrack Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:22:11 volumio volumio[1239]: info: Jan 14 09:22:11 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:11 volumio volumio[1239]: info: sendMpdCommand stop took 72 milliseconds Jan 14 09:22:11 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:11 volumio volumio[1239]: info: sendMpdCommand stop took 25 milliseconds Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand clear Jan 14 09:22:11 volumio volumio[1239]: info: Jan 14 09:22:11 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:11 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:11 volumio volumio[1239]: info: Jan 14 09:22:11 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:11 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:11 volumio volumio[1239]: info: Jan 14 09:22:11 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:22:11 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:22:11 volumio volumio[1239]: info: sendMpdCommand status took 9 milliseconds Jan 14 09:22:11 volumio volumio[1239]: info: sendMpdCommand clear took 8 milliseconds Jan 14 09:22:11 volumio volumio[1239]: info: sendMpdCommand status took 6 milliseconds Jan 14 09:22:11 volumio volumio[1239]: info: sendMpdCommand status took 4 milliseconds Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand load "https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac" Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:11 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:11 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:22:11 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:11 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:11 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:11 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:11 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:22:11 volumio volumio[1239]: info: ------------------------------ 65ms Jan 14 09:22:11 volumio volumio[1239]: info: ------------------------------ 62ms Jan 14 09:22:11 volumio volumio[1239]: info: Jan 14 09:22:11 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:22:11 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:22:11 volumio volumio[1239]: info: Jan 14 09:22:11 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:22:11 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:22:11 volumio volumio[1239]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Jan 14 09:22:11 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:22:11 volumio volumio[1239]: info: ------------------------------ 81ms Jan 14 09:22:11 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 78 milliseconds Jan 14 09:22:11 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:11 volumio volumio[1239]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 14 09:22:11 volumio volumio[1239]: info: ------------------------------ 92ms Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand add "https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac" Jan 14 09:22:12 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:22:12 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 205ms Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 204ms Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:22:12 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand add "https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac" took 4 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand play Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:22:12 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:22:12 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 7ms Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand play took 6 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 5ms Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 4ms Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand status took 7 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand status took 5 milliseconds Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand status took 11 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand status took 11 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand status took 6 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand status took 4 milliseconds Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":null,"album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:22:12 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":null,"album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:22:12 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:22:12 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 55ms Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 97ms Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:22:12 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:22:12 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:22:12 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:22:12 volumio volumio[1239]: info: Jan 14 09:22:12 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 87 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 88 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 86 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 87 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 11ms Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand status took 10 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 9ms Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand status took 8 milliseconds Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":null,"album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:22:12 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:22:12 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":null,"album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:22:12 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:22:12 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":null,"album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:22:12 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:22:12 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":null,"album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:22:12 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:22:12 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 383ms Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 383ms Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 378ms Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 377ms Jan 14 09:22:12 volumio volumio[1239]: info: ------------------------------ 384ms Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand status took 380 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 374 milliseconds Jan 14 09:22:12 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 374 milliseconds Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:12 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:22:12 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:22:12 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:22:12 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:22:12 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:12 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:13 volumio volumio[1239]: info: ------------------------------ 513ms Jan 14 09:22:13 volumio volumio[1239]: info: ------------------------------ 512ms Jan 14 09:22:13 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 130 milliseconds Jan 14 09:22:13 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:22:13 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:22:13 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:22:13 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:13 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:22:13 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:22:13 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:22:13 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:22:13 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:22:13 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 14 09:22:13 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:13 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:22:13 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:13 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:22:13 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:22:13 volumio volumio[1239]: info: ------------------------------ 569ms Jan 14 09:22:15 volumio go-librespot[2556]: time="2026-01-14T09:22:15+04:00" level=trace msg="received accesspoint ping" Jan 14 09:22:15 volumio go-librespot[2556]: time="2026-01-14T09:22:15+04:00" level=trace msg="received accesspoint pong ack" Jan 14 09:22:16 volumio go-librespot[2556]: time="2026-01-14T09:22:16+04:00" level=trace msg="sent dealer ping" Jan 14 09:22:16 volumio go-librespot[2556]: time="2026-01-14T09:22:16+04:00" level=trace msg="received dealer pong" Jan 14 09:22:46 volumio go-librespot[2556]: time="2026-01-14T09:22:46+04:00" level=trace msg="sent dealer ping" Jan 14 09:22:46 volumio go-librespot[2556]: time="2026-01-14T09:22:46+04:00" level=trace msg="received dealer pong" Jan 14 09:22:55 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 14 09:22:55 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:22:59 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 14 09:22:59 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:04 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::ClearQueue Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::stPlaybackTimer Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::serviceStop Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::serviceStop Jan 14 09:23:04 volumio volumio[1239]: info: [1768368184318] ControllerWebradio::stop Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::clearPlayQueue Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::addQueueItems Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::addQueueItems Jan 14 09:23:04 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:04 volumio volumio[1239]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s338298&filter=s:g59:country Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 14 09:23:04 volumio volumio[1239]: info: Jan 14 09:23:04 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:04 volumio volumio[1239]: info: sendMpdCommand stop took 120 milliseconds Jan 14 09:23:04 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:04 volumio volumio[1239]: info: Jan 14 09:23:04 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:04 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:04 volumio volumio[1239]: info: Jan 14 09:23:04 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:04 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:04 volumio volumio[1239]: info: sendMpdCommand status took 9 milliseconds Jan 14 09:23:04 volumio volumio[1239]: info: sendMpdCommand status took 7 milliseconds Jan 14 09:23:04 volumio volumio[1239]: info: sendMpdCommand status took 4 milliseconds Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:04 volumio volumio[1239]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Jan 14 09:23:04 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 14 09:23:04 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 14 09:23:04 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:04 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:04 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:23:04 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::syncState stateService stop Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: No code Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:23:04 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::syncState stateService stop Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: No code Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://dzen.ru/away?to=http%3A%2F%2Fstream.radioparadise.com%2Fmellow-flac","trackType":""} Jan 14 09:23:04 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::syncState stateService stop Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: No code Jan 14 09:23:04 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:04 volumio volumio[1239]: info: ------------------------------ 242ms Jan 14 09:23:04 volumio volumio[1239]: info: ------------------------------ 238ms Jan 14 09:23:04 volumio volumio[1239]: info: ------------------------------ 237ms Jan 14 09:23:05 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:23:05 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:23:05 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:23:05 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:23:05 volumio volumio[1239]: info: CoreCommandRouter::volumioPlay Jan 14 09:23:05 volumio volumio[1239]: info: CoreStateMachine::play index 0 Jan 14 09:23:05 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:05 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:23:05 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:05 volumio volumio[1239]: info: CoreStateMachine::play index undefined Jan 14 09:23:05 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:05 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:05 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Jan 14 09:23:05 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:05 volumio volumio[1239]: info: [1768368185250] ControllerWebradio::clearAddPlayTrack Jan 14 09:23:05 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:23:05 volumio volumio[1239]: info: sendMpdCommand stop took 7 milliseconds Jan 14 09:23:05 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand clear Jan 14 09:23:05 volumio volumio[1239]: info: Jan 14 09:23:05 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:05 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:05 volumio volumio[1239]: info: sendMpdCommand clear took 3 milliseconds Jan 14 09:23:05 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand load "https://air.unmixed.ru/record320" Jan 14 09:23:05 volumio volumio[1239]: info: Jan 14 09:23:05 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:05 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:05 volumio volumio[1239]: info: Jan 14 09:23:05 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:05 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:05 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:05 volumio volumio[1239]: info: ------------------------------ 10ms Jan 14 09:23:06 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand add "https://air.unmixed.ru/record320" Jan 14 09:23:06 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:06 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:06 volumio volumio[1239]: info: Jan 14 09:23:06 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:06 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:06 volumio volumio[1239]: info: ------------------------------ 1290ms Jan 14 09:23:06 volumio volumio[1239]: info: ------------------------------ 1288ms Jan 14 09:23:06 volumio volumio[1239]: info: sendMpdCommand add "https://air.unmixed.ru/record320" took 5 milliseconds Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 14 09:23:06 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand play Jan 14 09:23:06 volumio volumio[1239]: info: Jan 14 09:23:06 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:06 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:06 volumio volumio[1239]: info: Jan 14 09:23:06 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:06 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:06 volumio volumio[1239]: info: ------------------------------ 12ms Jan 14 09:23:06 volumio volumio[1239]: info: sendMpdCommand play took 8 milliseconds Jan 14 09:23:06 volumio volumio[1239]: info: ------------------------------ 8ms Jan 14 09:23:06 volumio volumio[1239]: info: ------------------------------ 5ms Jan 14 09:23:06 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:06 volumio volumio[1239]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::ClearQueue Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:06 volumio volumio[1239]: info: CorePlayQueue::clearPlayQueue Jan 14 09:23:06 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:23:06 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::addQueueItems Jan 14 09:23:06 volumio volumio[1239]: info: CorePlayQueue::addQueueItems Jan 14 09:23:06 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:06 volumio volumio[1239]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s338298&filter=s:g59:country Jan 14 09:23:06 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 14 09:23:06 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:23:06 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:23:06 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:23:06 volumio volumio[1239]: info: CoreCommandRouter::volumioPlay Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::play index 0 Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::play index undefined Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:06 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:06 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Jan 14 09:23:06 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:06 volumio volumio[1239]: info: [1768368186808] ControllerWebradio::clearAddPlayTrack Jan 14 09:23:06 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:23:07 volumio volumio[1239]: info: Jan 14 09:23:07 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:07 volumio volumio[1239]: info: sendMpdCommand stop took 706 milliseconds Jan 14 09:23:07 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:07 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:07 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand clear Jan 14 09:23:07 volumio volumio[1239]: info: Jan 14 09:23:07 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:07 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:07 volumio volumio[1239]: info: sendMpdCommand status took 3 milliseconds Jan 14 09:23:07 volumio volumio[1239]: info: sendMpdCommand clear took 4 milliseconds Jan 14 09:23:07 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:07 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:07 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand load "https://air.unmixed.ru/record320" Jan 14 09:23:07 volumio volumio[1239]: info: Jan 14 09:23:07 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:07 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:07 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:07 volumio volumio[1239]: info: Jan 14 09:23:07 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:07 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:07 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:07 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:07 volumio volumio[1239]: info: ------------------------------ 14ms Jan 14 09:23:07 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 14 09:23:07 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:07 volumio volumio[1239]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 14 09:23:07 volumio volumio[1239]: info: ------------------------------ 22ms Jan 14 09:23:08 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand add "https://air.unmixed.ru/record320" Jan 14 09:23:08 volumio volumio[1239]: info: sendMpdCommand status took 1236 milliseconds Jan 14 09:23:08 volumio volumio[1239]: info: sendMpdCommand status took 1234 milliseconds Jan 14 09:23:08 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:08 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:08 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:08 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:08 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:08 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:08 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:08 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:08 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:08 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:23:08 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:08 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:08 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:08 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:08 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:08 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:08 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:23:08 volumio volumio[1239]: info: ------------------------------ 1287ms Jan 14 09:23:08 volumio volumio[1239]: info: ------------------------------ 1284ms Jan 14 09:23:08 volumio volumio[1239]: info: Jan 14 09:23:08 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:08 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:08 volumio volumio[1239]: info: Jan 14 09:23:08 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:08 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:08 volumio volumio[1239]: info: Jan 14 09:23:08 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:08 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:08 volumio volumio[1239]: info: sendMpdCommand add "https://air.unmixed.ru/record320" took 59 milliseconds Jan 14 09:23:08 volumio volumio[1239]: info: ------------------------------ 7ms Jan 14 09:23:08 volumio volumio[1239]: info: ------------------------------ 5ms Jan 14 09:23:08 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 14 09:23:08 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand play Jan 14 09:23:08 volumio volumio[1239]: info: Jan 14 09:23:08 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:08 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:08 volumio volumio[1239]: info: Jan 14 09:23:08 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:08 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:08 volumio volumio[1239]: info: ------------------------------ 29ms Jan 14 09:23:08 volumio volumio[1239]: info: sendMpdCommand play took 26 milliseconds Jan 14 09:23:08 volumio volumio[1239]: info: ------------------------------ 25ms Jan 14 09:23:08 volumio volumio[1239]: info: ------------------------------ 23ms Jan 14 09:23:09 volumio volumio[1239]: info: Removing uri http://opml.radiotime.com/Tune.ashx?id=s338298&filter=s:g59:country from favourites Jan 14 09:23:09 volumio volumio[1239]: info: Saving local item radio-favourites Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 10 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 17 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 13 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 12 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 7 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 5 milliseconds Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 42ms Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 287ms Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 285ms Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 284ms Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 278ms Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:10 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 271 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 345ms Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:10 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:10 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:10 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 210ms Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 208 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 19ms Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 16 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 16ms Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 13 milliseconds Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:10 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:10 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:10 volumio volumio[1239]: info: Jan 14 09:23:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 21ms Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 18 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 13ms Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 12 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 10ms Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand status took 9 milliseconds Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 372ms Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 180ms Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 177ms Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 148 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 147 milliseconds Jan 14 09:23:10 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 147 milliseconds Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:10 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:10 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:10 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 314ms Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 305ms Jan 14 09:23:10 volumio volumio[1239]: info: ------------------------------ 303ms Jan 14 09:23:15 volumio volumio[1239]: info: CoreCommandRouter::volumioGetState Jan 14 09:23:16 volumio go-librespot[2556]: time="2026-01-14T09:23:16+04:00" level=trace msg="sent dealer ping" Jan 14 09:23:16 volumio go-librespot[2556]: time="2026-01-14T09:23:16+04:00" level=trace msg="received dealer pong" Jan 14 09:23:19 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 14 09:23:19 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:20 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 14 09:23:20 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:21 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::ClearQueue Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::stPlaybackTimer Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::serviceStop Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::serviceStop Jan 14 09:23:21 volumio volumio[1239]: info: [1768368201872] ControllerWebradio::stop Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::clearPlayQueue Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::addQueueItems Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::addQueueItems Jan 14 09:23:21 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:21 volumio volumio[1239]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s338298&filter=s:g59:country Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::volumioPlay Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::play index 0 Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::play index undefined Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:21 volumio volumio[1239]: info: [1768368201921] ControllerWebradio::clearAddPlayTrack Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:23:21 volumio volumio[1239]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Jan 14 09:23:21 volumio volumio[1239]: info: Jan 14 09:23:21 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:21 volumio volumio[1239]: info: sendMpdCommand stop took 93 milliseconds Jan 14 09:23:21 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:21 volumio volumio[1239]: info: sendMpdCommand stop took 45 milliseconds Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand clear Jan 14 09:23:21 volumio volumio[1239]: info: Jan 14 09:23:21 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:21 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:21 volumio volumio[1239]: info: Jan 14 09:23:21 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:21 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:21 volumio volumio[1239]: info: Jan 14 09:23:21 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:21 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:21 volumio volumio[1239]: info: sendMpdCommand status took 12 milliseconds Jan 14 09:23:21 volumio volumio[1239]: info: sendMpdCommand clear took 10 milliseconds Jan 14 09:23:21 volumio volumio[1239]: info: sendMpdCommand status took 9 milliseconds Jan 14 09:23:21 volumio volumio[1239]: info: sendMpdCommand status took 6 milliseconds Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand load "https://air.unmixed.ru/record320" Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:21 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:21 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:21 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:21 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:21 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:22 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:22 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:23:22 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:22 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:22 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:22 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:22 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:22 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:22 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:23:22 volumio volumio[1239]: info: ------------------------------ 76ms Jan 14 09:23:22 volumio volumio[1239]: info: ------------------------------ 76ms Jan 14 09:23:22 volumio volumio[1239]: info: Jan 14 09:23:22 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:22 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:22 volumio volumio[1239]: info: Jan 14 09:23:22 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:22 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:23 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:23 volumio volumio[1239]: info: ------------------------------ 1259ms Jan 14 09:23:23 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 1255 milliseconds Jan 14 09:23:23 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand add "https://air.unmixed.ru/record320" Jan 14 09:23:23 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:23 volumio volumio[1239]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 14 09:23:23 volumio volumio[1239]: info: ------------------------------ 1275ms Jan 14 09:23:23 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:23 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:23 volumio volumio[1239]: info: Jan 14 09:23:23 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:23 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:23 volumio volumio[1239]: info: ------------------------------ 1193ms Jan 14 09:23:23 volumio volumio[1239]: info: ------------------------------ 1191ms Jan 14 09:23:23 volumio volumio[1239]: info: sendMpdCommand add "https://air.unmixed.ru/record320" took 6 milliseconds Jan 14 09:23:23 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 14 09:23:23 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand play Jan 14 09:23:23 volumio volumio[1239]: info: Jan 14 09:23:23 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:23 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:23 volumio volumio[1239]: info: Jan 14 09:23:23 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:23 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:23 volumio volumio[1239]: info: ------------------------------ 14ms Jan 14 09:23:23 volumio volumio[1239]: info: sendMpdCommand play took 11 milliseconds Jan 14 09:23:23 volumio volumio[1239]: info: ------------------------------ 11ms Jan 14 09:23:23 volumio volumio[1239]: info: ------------------------------ 9ms Jan 14 09:23:24 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::ClearQueue Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::clearPlayQueue Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::addQueueItems Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::addQueueItems Jan 14 09:23:24 volumio volumio[1239]: info: Preload queue cleared Jan 14 09:23:24 volumio volumio[1239]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s340306&filter=s:g4139:country Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand status took 12 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand status took 13 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand status took 12 milliseconds Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand status took 32 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 28 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 25 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand status took 20 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand status took 17 milliseconds Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:24 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:24 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:24 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:24 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:24 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: ------------------------------ 112ms Jan 14 09:23:24 volumio volumio[1239]: info: ------------------------------ 192ms Jan 14 09:23:24 volumio volumio[1239]: info: ------------------------------ 194ms Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:24 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 157 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 157 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 157 milliseconds Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: 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":"record320","artist":null,"album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:24 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:24 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: 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":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:24 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:24 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: 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":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:24 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:24 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: ------------------------------ 375ms Jan 14 09:23:24 volumio volumio[1239]: info: ------------------------------ 359ms Jan 14 09:23:24 volumio volumio[1239]: info: ------------------------------ 355ms Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:24 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:24 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:24 volumio volumio[1239]: info: Jan 14 09:23:24 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:24 volumio volumio[1239]: info: ------------------------------ 280ms Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand status took 270 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: ------------------------------ 10ms Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand status took 9 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: ------------------------------ 8ms Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand status took 7 milliseconds Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 14 09:23:24 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:24 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:24 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:24 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1185,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:24 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:24 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:24 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:24 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:24 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:24 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1185,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"PAKITO/POYLOW/NITO-ONNA - Living On Video (All Tonight) ","artist":"Radio Record","album":null,"uri":"https://air.unmixed.ru/record320","trackType":""} Jan 14 09:23:24 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:24 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:25 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:25 volumio volumio[1239]: info: ------------------------------ 405ms Jan 14 09:23:25 volumio volumio[1239]: info: ------------------------------ 139ms Jan 14 09:23:25 volumio volumio[1239]: info: ------------------------------ 137ms Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::saveQueue Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::volumioPlay Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::play index 0 Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::stPlaybackTimer Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::serviceStop Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::serviceStop Jan 14 09:23:25 volumio volumio[1239]: info: [1768368205183] ControllerWebradio::stop Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:23:25 volumio volumio[1239]: info: sendMpdCommand stop took 38 milliseconds Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::play index undefined Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: [1768368205224] ControllerWebradio::clearAddPlayTrack Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:23:25 volumio volumio[1239]: info: Jan 14 09:23:25 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:25 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:25 volumio volumio[1239]: info: sendMpdCommand stop took 3 milliseconds Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand clear Jan 14 09:23:25 volumio volumio[1239]: info: Jan 14 09:23:25 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:25 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:25 volumio volumio[1239]: info: Jan 14 09:23:25 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:25 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:25 volumio volumio[1239]: info: Jan 14 09:23:25 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:25 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:25 volumio volumio[1239]: info: sendMpdCommand status took 9 milliseconds Jan 14 09:23:25 volumio volumio[1239]: info: sendMpdCommand clear took 9 milliseconds Jan 14 09:23:25 volumio volumio[1239]: info: sendMpdCommand status took 6 milliseconds Jan 14 09:23:25 volumio volumio[1239]: info: sendMpdCommand status took 5 milliseconds Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand load "https://air.unmixed.ru/marusyafm" Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:25 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:23:25 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:25 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:25 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:25 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:23:25 volumio volumio[1239]: info: ------------------------------ 56ms Jan 14 09:23:25 volumio volumio[1239]: info: ------------------------------ 55ms Jan 14 09:23:25 volumio volumio[1239]: info: Jan 14 09:23:25 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:25 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:25 volumio volumio[1239]: info: Jan 14 09:23:25 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:25 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:25 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:25 volumio volumio[1239]: info: ------------------------------ 59ms Jan 14 09:23:25 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 57 milliseconds Jan 14 09:23:25 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:25 volumio volumio[1239]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 14 09:23:25 volumio volumio[1239]: info: ------------------------------ 71ms Jan 14 09:23:26 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand add "https://air.unmixed.ru/marusyafm" Jan 14 09:23:26 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:26 volumio volumio[1239]: error: updateQueue error: null Jan 14 09:23:26 volumio volumio[1239]: info: ------------------------------ 1267ms Jan 14 09:23:26 volumio volumio[1239]: info: ------------------------------ 1265ms Jan 14 09:23:26 volumio volumio[1239]: info: Jan 14 09:23:26 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:26 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:26 volumio volumio[1239]: info: sendMpdCommand add "https://air.unmixed.ru/marusyafm" took 5 milliseconds Jan 14 09:23:26 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 14 09:23:26 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand play Jan 14 09:23:26 volumio volumio[1239]: info: Jan 14 09:23:26 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:26 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:26 volumio volumio[1239]: info: Jan 14 09:23:26 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:26 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:26 volumio volumio[1239]: info: ------------------------------ 8ms Jan 14 09:23:26 volumio volumio[1239]: info: sendMpdCommand play took 8 milliseconds Jan 14 09:23:26 volumio volumio[1239]: info: ------------------------------ 6ms Jan 14 09:23:26 volumio volumio[1239]: info: ------------------------------ 6ms Jan 14 09:23:27 volumio volumio[1239]: info: Jan 14 09:23:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:27 volumio volumio[1239]: info: Jan 14 09:23:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:27 volumio volumio[1239]: info: Jan 14 09:23:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:27 volumio volumio[1239]: info: Jan 14 09:23:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:27 volumio volumio[1239]: info: Jan 14 09:23:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:27 volumio volumio[1239]: info: Jan 14 09:23:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand status took 18 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand status took 18 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand status took 16 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand status took 14 milliseconds Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand status took 24 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand status took 18 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: 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":"marusyafm","artist":null,"album":null,"uri":"https://air.unmixed.ru/marusyafm","trackType":""} Jan 14 09:23:27 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: 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":"marusyafm","artist":null,"album":null,"uri":"https://air.unmixed.ru/marusyafm","trackType":""} Jan 14 09:23:27 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:27 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: 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":"marusyafm","artist":null,"album":null,"uri":"https://air.unmixed.ru/marusyafm","trackType":""} Jan 14 09:23:27 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:27 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: 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":"marusyafm","artist":null,"album":null,"uri":"https://air.unmixed.ru/marusyafm","trackType":""} Jan 14 09:23:27 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:27 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:27 volumio volumio[1239]: info: ------------------------------ 102ms Jan 14 09:23:27 volumio volumio[1239]: info: ------------------------------ 213ms Jan 14 09:23:27 volumio volumio[1239]: info: ------------------------------ 212ms Jan 14 09:23:27 volumio volumio[1239]: info: ------------------------------ 211ms Jan 14 09:23:27 volumio volumio[1239]: info: Jan 14 09:23:27 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:27 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:27 volumio volumio[1239]: info: Jan 14 09:23:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 188 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 188 milliseconds Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:27 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: 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":"marusyafm","artist":null,"album":null,"uri":"https://air.unmixed.ru/marusyafm","trackType":""} Jan 14 09:23:27 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:27 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:27 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: 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":"marusyafm","artist":null,"album":null,"uri":"https://air.unmixed.ru/marusyafm","trackType":""} Jan 14 09:23:27 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:27 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:28 volumio volumio[1239]: info: ------------------------------ 310ms Jan 14 09:23:28 volumio volumio[1239]: info: ------------------------------ 306ms Jan 14 09:23:28 volumio volumio[1239]: info: Jan 14 09:23:28 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:28 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:28 volumio volumio[1239]: info: Jan 14 09:23:28 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:28 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:28 volumio volumio[1239]: info: Jan 14 09:23:28 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Jan 14 09:23:28 volumio volumio[1239]: info: Ignoring MPD Status Update Jan 14 09:23:28 volumio volumio[1239]: info: Jan 14 09:23:28 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:28 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:28 volumio volumio[1239]: info: ------------------------------ 186ms Jan 14 09:23:28 volumio volumio[1239]: info: sendMpdCommand status took 184 milliseconds Jan 14 09:23:28 volumio volumio[1239]: info: ------------------------------ 39ms Jan 14 09:23:28 volumio volumio[1239]: info: sendMpdCommand status took 37 milliseconds Jan 14 09:23:28 volumio volumio[1239]: info: ------------------------------ 37ms Jan 14 09:23:28 volumio volumio[1239]: info: sendMpdCommand status took 36 milliseconds Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:28 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 14 09:23:28 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 14 09:23:28 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:28 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:28 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:28 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"С неба - ELMAN;TRIDA","artist":null,"album":null,"uri":"https://air.unmixed.ru/marusyafm","trackType":""} Jan 14 09:23:28 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:28 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:28 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:28 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1186,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"С неба - ELMAN;TRIDA","artist":null,"album":null,"uri":"https://air.unmixed.ru/marusyafm","trackType":""} Jan 14 09:23:28 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:28 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:28 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:28 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1186,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"С неба - ELMAN;TRIDA","artist":null,"album":null,"uri":"https://air.unmixed.ru/marusyafm","trackType":""} Jan 14 09:23:28 volumio volumio[1239]: verbose: CURRENT POSITION 0 Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Jan 14 09:23:28 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:28 volumio volumio[1239]: info: ------------------------------ 329ms Jan 14 09:23:28 volumio volumio[1239]: info: ------------------------------ 181ms Jan 14 09:23:28 volumio volumio[1239]: info: ------------------------------ 182ms Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::volumioStop Jan 14 09:23:41 volumio volumio[1239]: info: CoreStateMachine::stop Jan 14 09:23:41 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 09:23:41 volumio volumio[1239]: info: CoreStateMachine::stPlaybackTimer Jan 14 09:23:41 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Jan 14 09:23:41 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Jan 14 09:23:41 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:41 volumio volumio[1239]: info: CoreStateMachine::serviceStop Jan 14 09:23:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::serviceStop Jan 14 09:23:41 volumio volumio[1239]: info: [1768368221408] ControllerWebradio::stop Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Jan 14 09:23:41 volumio volumio[1239]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Jan 14 09:23:41 volumio volumio[1239]: info: Jan 14 09:23:41 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:41 volumio volumio[1239]: info: sendMpdCommand stop took 48 milliseconds Jan 14 09:23:41 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:41 volumio volumio[1239]: info: Jan 14 09:23:41 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:41 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:41 volumio volumio[1239]: info: Jan 14 09:23:41 volumio volumio[1239]: ---------------------------- MPD announces state update: player Jan 14 09:23:41 volumio volumio[1239]: info: ControllerMpd::getState Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Jan 14 09:23:41 volumio volumio[1239]: info: sendMpdCommand status took 6 milliseconds Jan 14 09:23:41 volumio volumio[1239]: info: sendMpdCommand status took 4 milliseconds Jan 14 09:23:41 volumio volumio[1239]: info: sendMpdCommand status took 3 milliseconds Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::parseState Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 09:23:41 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 14 09:23:41 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 14 09:23:41 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:41 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Jan 14 09:23:41 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:41 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:41 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:23:41 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:41 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:41 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:23:41 volumio volumio[1239]: info: ControllerMpd::pushState Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Jan 14 09:23:41 volumio volumio[1239]: info: CoreStateMachine::pushState Jan 14 09:23:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:41 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Jan 14 09:23:41 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:41 volumio volumio[1239]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 14 09:23:41 volumio volumio[1239]: info: ------------------------------ 80ms Jan 14 09:23:41 volumio volumio[1239]: info: ------------------------------ 79ms Jan 14 09:23:41 volumio volumio[1239]: info: ------------------------------ 78ms Jan 14 09:23:46 volumio volumio[1239]: info: CoreCommandRouter::volumioGetState Jan 14 09:23:46 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Jan 14 09:23:46 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 14 09:23:46 volumio go-librespot[2556]: time="2026-01-14T09:23:46+04:00" level=trace msg="sent dealer ping" Jan 14 09:23:46 volumio go-librespot[2556]: time="2026-01-14T09:23:46+04:00" level=trace msg="received dealer pong" Jan 14 09:23:48 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 14 09:23:48 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 14 09:23:48 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 14 09:23:48 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 14 09:23:48 volumio systemd[1]: setdatetime-helper.service: Consumed 1.796s CPU time. Jan 14 09:23:54 volumio sudo[4120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 14 09:23:54 volumio sudo[4120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 14 09:23:54 volumio sudo[4120]: pam_unix(sudo:session): session closed for user root Jan 14 09:23:54 volumio sudo[4122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Jan 14 09:23:54 volumio sudo[4122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 14 09:23:54 volumio volumio[1239]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 14 09:23:54 volumio sudo[4124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Jan 14 09:23:54 volumio volumio[1239]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 09:23:54 volumio sudo[4124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 14 09:23:54 volumio volumio[1239]: TypeError: Cannot read properties of undefined (reading 'split') Jan 14 09:23:54 volumio volumio[1239]: at /data/plugins/user_interface/touch_display/index.js:1274:37 Jan 14 09:23:54 volumio volumio[1239]: at ChildProcess.exithandler (node:child_process:413:7) Jan 14 09:23:54 volumio volumio[1239]: at ChildProcess.emit (node:events:514:28) Jan 14 09:23:54 volumio volumio[1239]: at maybeClose (node:internal/child_process:1105:16) Jan 14 09:23:54 volumio volumio[1239]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Jan 14 09:23:54 volumio volumio[1239]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 09:23:54 volumio sudo[4127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Jan 14 09:23:54 volumio sudo[4127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 14 09:23:54 volumio systemd[1]: Reloading. Jan 14 09:23:56 volumio sudo[4163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-14 09:22' Jan 14 09:23:56 volumio sudo[4163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"