Dec 20 14:36:06 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioStop Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreStateMachine::stop Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreStateMachine::stPlaybackTimer Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreStateMachine::updateTrackBlock Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrackBlock Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreStateMachine::serviceStop Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:07 volumiopi3 volumio[1166]: info: CoreCommandRouter::serviceStop Dec 20 14:36:07 volumiopi3 volumio[1166]: info: [1766230567321] ControllerWebradio::stop Dec 20 14:36:07 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 14:36:07 volumiopi3 volumio[1166]: info: sendMpdCommand stop took 22 milliseconds Dec 20 14:36:08 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPlay Dec 20 14:36:08 volumiopi3 volumio[1166]: info: CoreStateMachine::play index undefined Dec 20 14:36:08 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 14:36:08 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:08 volumiopi3 volumio[1166]: info: CoreStateMachine::startPlaybackTimer Dec 20 14:36:08 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:08 volumiopi3 volumio[1166]: info: [1766230568653] ControllerWebradio::clearAddPlayTrack Dec 20 14:36:08 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 14:36:08 volumiopi3 volumio[1166]: info: sendMpdCommand stop took 2 milliseconds Dec 20 14:36:08 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand clear Dec 20 14:36:08 volumiopi3 volumio[1166]: info: Dec 20 14:36:08 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:08 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:08 volumiopi3 volumio[1166]: info: sendMpdCommand clear took 3 milliseconds Dec 20 14:36:08 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand load "https://evcast.mediacp.eu:1965/stream" Dec 20 14:36:08 volumiopi3 volumio[1166]: info: Dec 20 14:36:08 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:08 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:08 volumiopi3 volumio[1166]: error: updateQueue error: null Dec 20 14:36:08 volumiopi3 volumio[1166]: info: ------------------------------ 10ms Dec 20 14:36:10 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand add "https://evcast.mediacp.eu:1965/stream" Dec 20 14:36:10 volumiopi3 volumio[1166]: error: updateQueue error: null Dec 20 14:36:10 volumiopi3 volumio[1166]: info: ------------------------------ 1954ms Dec 20 14:36:10 volumiopi3 volumio[1166]: info: Dec 20 14:36:10 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:10 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:10 volumiopi3 volumio[1166]: info: sendMpdCommand add "https://evcast.mediacp.eu:1965/stream" took 5 milliseconds Dec 20 14:36:10 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 14:36:10 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand play Dec 20 14:36:10 volumiopi3 volumio[1166]: info: Dec 20 14:36:10 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:10 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:10 volumiopi3 volumio[1166]: info: ------------------------------ 7ms Dec 20 14:36:10 volumiopi3 volumio[1166]: info: sendMpdCommand play took 5 milliseconds Dec 20 14:36:10 volumiopi3 volumio[1166]: info: ------------------------------ 5ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand status took 6 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand status took 4 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand status took 6 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus stop Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 37ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 53ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand status took 43 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 40 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 94ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 82 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 181ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 63ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand status took 61 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 9ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand status took 7 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Dec 20 14:36:12 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 33ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand status took 30 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 27 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 27 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 26ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand status took 24 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Cretu & Thiers - When Love Is The Missing Word (1988)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Cretu & Thiers - When Love Is The Missing Word (1988)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 172ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 119ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 81 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 79 milliseconds Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Cretu & Thiers - When Love Is The Missing Word (1988)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Cretu & Thiers - When Love Is The Missing Word (1988)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:12 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:12 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 186ms Dec 20 14:36:12 volumiopi3 volumio[1166]: info: ------------------------------ 178ms Dec 20 14:36:14 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 20 14:36:14 volumiopi3 volumio[1166]: info: Preload queue cleared Dec 20 14:36:20 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 20 14:36:20 volumiopi3 volumio[1166]: info: Preload queue cleared Dec 20 14:36:26 volumiopi3 volumio[1166]: info: Preload queue cleared Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioAddQueueItems Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::addQueueItems Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CorePlayQueue::addQueueItems Dec 20 14:36:26 volumiopi3 volumio[1166]: info: Preload queue cleared Dec 20 14:36:26 volumiopi3 volumio[1166]: info: Adding Item to queue: https://evcast.mediacp.eu:1965/stream Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushQueue Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CorePlayQueue::saveQueue Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::updateTrackBlock Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrackBlock Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPlay Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::play index 0 Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::stop Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::stPlaybackTimer Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::updateTrackBlock Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrackBlock Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::serviceStop Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreCommandRouter::serviceStop Dec 20 14:36:26 volumiopi3 volumio[1166]: info: [1766230586075] ControllerWebradio::stop Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 14:36:26 volumiopi3 volumio[1166]: info: sendMpdCommand stop took 37 milliseconds Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::play index undefined Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CoreStateMachine::startPlaybackTimer Dec 20 14:36:26 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:26 volumiopi3 volumio[1166]: info: [1766230586115] ControllerWebradio::clearAddPlayTrack Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 14:36:26 volumiopi3 volumio[1166]: info: Dec 20 14:36:26 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:26 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:26 volumiopi3 volumio[1166]: info: Dec 20 14:36:26 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:26 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:26 volumiopi3 volumio[1166]: info: sendMpdCommand stop took 10 milliseconds Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand clear Dec 20 14:36:26 volumiopi3 volumio[1166]: info: Dec 20 14:36:26 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:26 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:26 volumiopi3 volumio[1166]: info: sendMpdCommand status took 9 milliseconds Dec 20 14:36:26 volumiopi3 volumio[1166]: info: sendMpdCommand status took 7 milliseconds Dec 20 14:36:26 volumiopi3 volumio[1166]: info: sendMpdCommand clear took 5 milliseconds Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand load "https://evcast.mediacp.eu:1965/stream" Dec 20 14:36:26 volumiopi3 volumio[1166]: info: Dec 20 14:36:26 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:26 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:26 volumiopi3 volumio[1166]: error: updateQueue error: null Dec 20 14:36:26 volumiopi3 volumio[1166]: info: ------------------------------ 13ms Dec 20 14:36:26 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 20 14:36:26 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:26 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:26 volumiopi3 volumio[1166]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 20 14:36:26 volumiopi3 volumio[1166]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 20 14:36:26 volumiopi3 volumio[1166]: info: ------------------------------ 29ms Dec 20 14:36:26 volumiopi3 volumio[1166]: info: ------------------------------ 26ms Dec 20 14:36:27 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand add "https://evcast.mediacp.eu:1965/stream" Dec 20 14:36:27 volumiopi3 volumio[1166]: error: updateQueue error: null Dec 20 14:36:27 volumiopi3 volumio[1166]: info: ------------------------------ 1432ms Dec 20 14:36:27 volumiopi3 volumio[1166]: info: sendMpdCommand add "https://evcast.mediacp.eu:1965/stream" took 1 milliseconds Dec 20 14:36:27 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 14:36:27 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand play Dec 20 14:36:27 volumiopi3 volumio[1166]: info: Dec 20 14:36:27 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:27 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:27 volumiopi3 volumio[1166]: info: Dec 20 14:36:27 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:27 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:27 volumiopi3 volumio[1166]: info: sendMpdCommand play took 7 milliseconds Dec 20 14:36:27 volumiopi3 volumio[1166]: info: ------------------------------ 5ms Dec 20 14:36:27 volumiopi3 volumio[1166]: info: ------------------------------ 3ms Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand status took 5 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand status took 5 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand status took 6 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand status took 5 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus stop Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ------------------------------ 33ms Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ------------------------------ 44ms Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ------------------------------ 126ms Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ------------------------------ 126ms Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ------------------------------ 9ms Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand status took 7 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ------------------------------ 6ms Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand status took 6 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Dec 20 14:36:28 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ------------------------------ 11ms Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand status took 10 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ------------------------------ 8ms Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand status took 7 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: 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":"Cretu & Thiers - When Love Is The Missing Word (1988)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: 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":"Cretu & Thiers - When Love Is The Missing Word (1988)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:28 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:28 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:28 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:29 volumiopi3 volumio[1166]: 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":"Cretu & Thiers - When Love Is The Missing Word (1988)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:29 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:29 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: ------------------------------ 108ms Dec 20 14:36:29 volumiopi3 volumio[1166]: info: ------------------------------ 106ms Dec 20 14:36:29 volumiopi3 volumio[1166]: info: ------------------------------ 106ms Dec 20 14:36:29 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 90 milliseconds Dec 20 14:36:29 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:29 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:29 volumiopi3 volumio[1166]: 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":"Cretu & Thiers - When Love Is The Missing Word (1988)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:29 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:29 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:29 volumiopi3 volumio[1166]: info: ------------------------------ 130ms Dec 20 14:36:34 volumiopi3 volumio[1166]: info: Dec 20 14:36:34 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:34 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:34 volumiopi3 volumio[1166]: info: Dec 20 14:36:34 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:34 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:34 volumiopi3 volumio[1166]: info: Dec 20 14:36:34 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:34 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:34 volumiopi3 volumio[1166]: info: Dec 20 14:36:34 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:34 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:34 volumiopi3 volumio[1166]: info: ------------------------------ 7ms Dec 20 14:36:34 volumiopi3 volumio[1166]: info: sendMpdCommand status took 5 milliseconds Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:34 volumiopi3 volumio[1166]: info: ------------------------------ 8ms Dec 20 14:36:34 volumiopi3 volumio[1166]: info: sendMpdCommand status took 5 milliseconds Dec 20 14:36:34 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:34 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6282,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Michael Jackson - Human Nature (1982)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:34 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: ------------------------------ 56ms Dec 20 14:36:34 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:34 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6282,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Michael Jackson - Human Nature (1982)","artist":"Radio SOVA","album":null,"uri":"https://evcast.mediacp.eu:1965/stream","trackType":""} Dec 20 14:36:34 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 0 Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:34 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:34 volumiopi3 volumio[1166]: info: ------------------------------ 101ms Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Preload queue cleared Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioAddQueueItems Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::addQueueItems Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::addQueueItems Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Preload queue cleared Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Adding Item to queue: http://stream.cassiopeia-station.ru:1130/stream Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushQueue Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::saveQueue Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::updateTrackBlock Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrackBlock Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPlay Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::play index 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::stop Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::stPlaybackTimer Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::updateTrackBlock Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrackBlock Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::serviceStop Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::serviceStop Dec 20 14:36:37 volumiopi3 volumio[1166]: info: [1766230597753] ControllerWebradio::stop Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand stop took 38 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::play index undefined Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::startPlaybackTimer Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: [1766230597795] ControllerWebradio::clearAddPlayTrack Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand status took 9 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand stop took 6 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand status took 3 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand clear Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand clear took 4 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand load "http://stream.cassiopeia-station.ru:1130/stream" Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 20 14:36:37 volumiopi3 volumio[1166]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ------------------------------ 43ms Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ------------------------------ 36ms Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand add "http://stream.cassiopeia-station.ru:1130/stream" Dec 20 14:36:37 volumiopi3 volumio[1166]: error: updateQueue error: null Dec 20 14:36:37 volumiopi3 volumio[1166]: error: updateQueue error: null Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ------------------------------ 68ms Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ------------------------------ 67ms Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand add "http://stream.cassiopeia-station.ru:1130/stream" took 5 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand play Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ------------------------------ 8ms Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand play took 6 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ------------------------------ 4ms Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand status took 5 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand status took 4 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Dec 20 14:36:37 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand status took 12 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: info: sendMpdCommand status took 5 milliseconds Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://stream.cassiopeia-station.ru:1130/stream","trackType":""} Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus stop Dec 20 14:36:37 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://stream.cassiopeia-station.ru:1130/stream","trackType":""} Dec 20 14:36:37 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:37 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:37 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 61ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 86ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Dec 20 14:36:38 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Dec 20 14:36:38 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Dec 20 14:36:38 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Dec 20 14:36:38 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 73 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 70 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://stream.cassiopeia-station.ru:1130/stream","trackType":""} Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: 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":"Boney M. - Belfast","artist":"Dancing Fox","album":null,"uri":"http://stream.cassiopeia-station.ru:1130/stream","trackType":""} Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 178ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 173ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Dec 20 14:36:38 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Dec 20 14:36:38 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 152ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand status took 148 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 151ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand status took 148 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 8ms Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Dec 20 14:36:38 volumiopi3 volumio[1166]: ---------------------------- MPD announces system playlist update Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Ignoring MPD Status Update Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Dec 20 14:36:38 volumiopi3 volumio[1166]: ---------------------------- MPD announces state update: player Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::getState Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand status took 12 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":882,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Boney M. - Belfast","artist":"Dancing Fox","album":null,"uri":"http://stream.cassiopeia-station.ru:1130/stream","trackType":""} Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":882,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Boney M. - Belfast","artist":"Dancing Fox","album":null,"uri":"http://stream.cassiopeia-station.ru:1130/stream","trackType":""} Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 252ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 253ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 101ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand status took 100 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 96 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseState Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Boney M. - Belfast","artist":"Dancing Fox","album":null,"uri":"http://stream.cassiopeia-station.ru:1130/stream","trackType":""} Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 151ms Dec 20 14:36:38 volumiopi3 volumio[1166]: info: sendMpdCommand playlistinfo took 94 milliseconds Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ControllerMpd::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::servicePushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CorePlayQueue::getTrack 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Boney M. - Belfast","artist":"Dancing Fox","album":null,"uri":"http://stream.cassiopeia-station.ru:1130/stream","trackType":""} Dec 20 14:36:38 volumiopi3 volumio[1166]: verbose: CURRENT POSITION 1 Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 20 14:36:38 volumiopi3 volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreStateMachine::pushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 20 14:36:38 volumiopi3 volumio[1166]: info: ------------------------------ 239ms Dec 20 14:36:40 volumiopi3 systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Dec 20 14:36:43 volumiopi3 systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Dec 20 14:36:43 volumiopi3 systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Dec 20 14:36:43 volumiopi3 systemd[1]: apt-daily-upgrade.service: Consumed 2.490s CPU time. Dec 20 14:36:46 volumiopi3 ntpd[998]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 14:36:46 volumiopi3 ntpd[998]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 20 14:36:46 volumiopi3 ntpd[998]: DNS: Pool taking: 195.90.182.235 Dec 20 14:36:46 volumiopi3 ntpd[998]: DNS: Pool taking: 195.122.253.38 Dec 20 14:36:46 volumiopi3 ntpd[998]: DNS: Pool taking: 195.161.115.4 Dec 20 14:36:46 volumiopi3 ntpd[998]: DNS: Pool taking: 95.165.76.66 Dec 20 14:36:46 volumiopi3 ntpd[998]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 20 14:36:54 volumiopi3 ntpd[998]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 14:36:54 volumiopi3 ntpd[998]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 20 14:36:54 volumiopi3 ntpd[998]: DNS: Pool skipping: 92.255.126.2 Dec 20 14:36:54 volumiopi3 ntpd[998]: DNS: Pool taking: 89.179.240.219 Dec 20 14:36:54 volumiopi3 ntpd[998]: DNS: Pool taking: 217.69.139.133 Dec 20 14:36:54 volumiopi3 ntpd[998]: DNS: Pool taking: 45.90.217.6 Dec 20 14:36:54 volumiopi3 ntpd[998]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 20 14:36:55 volumiopi3 ntpd[998]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 14:36:55 volumiopi3 ntpd[998]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 20 14:36:55 volumiopi3 ntpd[998]: DNS: Pool skipping: 192.36.143.130 Dec 20 14:36:55 volumiopi3 ntpd[998]: DNS: Pool skipping: 195.39.248.4 Dec 20 14:36:55 volumiopi3 ntpd[998]: DNS: Pool taking: 51.250.110.169 Dec 20 14:36:55 volumiopi3 ntpd[998]: DNS: Pool skipping: 93.191.12.44 Dec 20 14:36:55 volumiopi3 ntpd[998]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 20 14:36:56 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 20 14:36:59 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 14:36:59 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 14:36:59 volumiopi3 ntpd[998]: PROTO: 92.255.126.2 unlink local addr 192.168.1.7 -> Dec 20 14:37:01 volumiopi3 ntpd[998]: PROTO: 192.36.143.130 unlink local addr 192.168.1.7 -> Dec 20 14:37:01 volumiopi3 ntpd[998]: PROTO: 89.188.118.150 unlink local addr 192.168.1.7 -> Dec 20 14:37:02 volumiopi3 ntpd[998]: PROTO: 195.39.248.4 unlink local addr 192.168.1.7 -> Dec 20 14:37:02 volumiopi3 ntpd[998]: PROTO: 92.241.18.100 unlink local addr 192.168.1.7 -> Dec 20 14:37:03 volumiopi3 ntpd[998]: PROTO: 93.191.12.44 unlink local addr 192.168.1.7 -> Dec 20 14:37:04 volumiopi3 volumio[1166]: info: Enabling plugin touch_display Dec 20 14:37:04 volumiopi3 volumio[1166]: info: Loading plugin "touch_display"... Dec 20 14:37:04 volumiopi3 volumio[1166]: info: Applying required configuration parameters for plugin touch_display Dec 20 14:37:04 volumiopi3 volumio[1166]: info: PLUGIN START: touch_display Dec 20 14:37:04 volumiopi3 volumio[1166]: info: Loading i18n strings for locale ru Dec 20 14:37:04 volumiopi3 volumio[1166]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 20 14:37:04 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 14:37:04 volumiopi3 sudo[4138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 20 14:37:04 volumiopi3 volumio[1166]: info: touch_display: No backlight interface detected. Dec 20 14:37:04 volumiopi3 sudo[4138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:04 volumiopi3 sudo[4140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 20 14:37:04 volumiopi3 sudo[4140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:04 volumiopi3 sudo[4142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 20 14:37:04 volumiopi3 sudo[4142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:04 volumiopi3 volumio[1166]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 20 14:37:04 volumiopi3 sudo[4146]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 20 14:37:04 volumiopi3 sudo[4146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:04 volumiopi3 sudo[4146]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:04 volumiopi3 volumio[1166]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 20 14:37:04 volumiopi3 sudo[4149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 20 14:37:04 volumiopi3 sudo[4149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:04 volumiopi3 sudo[4149]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:04 volumiopi3 volumio[1166]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 20 14:37:04 volumiopi3 volumio[1166]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Dec 20 14:37:04 volumiopi3 systemd[1]: Reloading. Dec 20 14:37:05 volumiopi3 ntpd[998]: PROTO: 45.141.102.99 unlink local addr 192.168.1.7 -> Dec 20 14:37:06 volumiopi3 sudo[4142]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:06 volumiopi3 systemd[1]: Reloading. Dec 20 14:37:06 volumiopi3 volumio[1166]: info: touch_display: systemctl daemon-reload succeeded. Dec 20 14:37:06 volumiopi3 sudo[4176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 20 14:37:06 volumiopi3 volumio[1166]: info: Enabling plugin touch_display Dec 20 14:37:06 volumiopi3 volumio[1166]: info: Loading plugin "touch_display"... Dec 20 14:37:06 volumiopi3 sudo[4176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:06 volumiopi3 volumio[1166]: info: Applying required configuration parameters for plugin touch_display Dec 20 14:37:06 volumiopi3 volumio[1166]: info: PLUGIN START: touch_display Dec 20 14:37:06 volumiopi3 volumio[1166]: info: Loading i18n strings for locale ru Dec 20 14:37:06 volumiopi3 volumio[1166]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 20 14:37:06 volumiopi3 volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 14:37:06 volumiopi3 sudo[4181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 20 14:37:06 volumiopi3 sudo[4181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:06 volumiopi3 volumio[1166]: info: touch_display: No backlight interface detected. Dec 20 14:37:06 volumiopi3 sudo[4183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 20 14:37:06 volumiopi3 volumio[1166]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 20 14:37:06 volumiopi3 sudo[4183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:06 volumiopi3 sudo[4186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 20 14:37:06 volumiopi3 sudo[4186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:06 volumiopi3 sudo[4188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 20 14:37:06 volumiopi3 sudo[4188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:06 volumiopi3 sudo[4188]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:06 volumiopi3 volumio[1166]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 20 14:37:06 volumiopi3 sudo[4192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 20 14:37:06 volumiopi3 sudo[4192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:06 volumiopi3 sudo[4192]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:06 volumiopi3 volumio[1166]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 20 14:37:08 volumiopi3 sudo[4140]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:08 volumiopi3 volumio[1166]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 20 14:37:08 volumiopi3 systemd[1]: Reloading. Dec 20 14:37:09 volumiopi3 ntpd[998]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 14:37:10 volumiopi3 volumio[1166]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Dec 20 14:37:10 volumiopi3 sudo[4186]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:10 volumiopi3 systemd[1]: Reloading. Dec 20 14:37:10 volumiopi3 volumio[1166]: info: touch_display: systemctl daemon-reload succeeded. Dec 20 14:37:10 volumiopi3 sudo[4237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 20 14:37:10 volumiopi3 sudo[4237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:37:10 volumiopi3 ntpd[998]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 20 14:37:10 volumiopi3 ntpd[998]: DNS: Pool taking: 92.241.18.100 Dec 20 14:37:10 volumiopi3 ntpd[998]: DNS: Pool taking: 45.141.102.99 Dec 20 14:37:10 volumiopi3 ntpd[998]: DNS: Pool taking: 95.53.238.232 Dec 20 14:37:10 volumiopi3 ntpd[998]: DNS: Pool taking: 79.111.119.96 Dec 20 14:37:10 volumiopi3 ntpd[998]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 20 14:37:11 volumiopi3 sudo[4183]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:11 volumiopi3 volumio[1166]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 20 14:37:11 volumiopi3 systemd[1]: Stopping getty@tty1.service - Getty on tty1... Dec 20 14:37:11 volumiopi3 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Dec 20 14:37:11 volumiopi3 sudo[4176]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:11 volumiopi3 sudo[4237]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:11 volumiopi3 systemd[1]: getty@tty1.service: Deactivated successfully. Dec 20 14:37:11 volumiopi3 systemd[1]: Stopped getty@tty1.service - Getty on tty1. Dec 20 14:37:11 volumiopi3 volumio[1166]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 20 14:37:11 volumiopi3 volumio[1166]: info: touch_display: Volumio Kiosk started. Dec 20 14:37:11 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 20 14:37:11 volumiopi3 volumio[1166]: info: Done. Dec 20 14:37:11 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: Volumio Kiosk started. Dec 20 14:37:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 20 14:37:12 volumiopi3 volumio[1166]: info: Done. Dec 20 14:37:12 volumiopi3 volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 20 14:37:12 volumiopi3 sudo[4138]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 20 14:37:12 volumiopi3 sudo[4181]: pam_unix(sudo:session): session closed for user root Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 startx[4290]: X.Org X Server 1.21.1.7 Dec 20 14:37:12 volumiopi3 startx[4290]: X Protocol Version 11, Revision 0 Dec 20 14:37:12 volumiopi3 startx[4290]: Current Operating System: Linux volumiopi3 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l Dec 20 14:37:12 volumiopi3 startx[4290]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=aad31cad-393a-4fed-8407-009f0f1afa8c imgfile=/volumio_current.sqsh bootpart=UUID=3B14-9250 datapart=UUID=1039c36d-c049-4f15-b227-4d4e96b781b2 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 20 14:37:12 volumiopi3 startx[4290]: xorg-server 2:21.1.7-3+rpt3+deb12u10 (https://www.debian.org/support) Dec 20 14:37:12 volumiopi3 startx[4290]: Current version of pixman: 0.44.0 Dec 20 14:37:12 volumiopi3 startx[4290]: Before reporting problems, check http://wiki.x.org Dec 20 14:37:12 volumiopi3 startx[4290]: to make sure that you have the latest version. Dec 20 14:37:12 volumiopi3 startx[4290]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 20 14:37:12 volumiopi3 startx[4290]: (++) from command line, (!!) notice, (II) informational, Dec 20 14:37:12 volumiopi3 startx[4290]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 20 14:37:12 volumiopi3 startx[4290]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Dec 20 14:37:12 2025 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 startx[4290]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 20 14:37:12 volumiopi3 startx[4290]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:12 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:13 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: About to detect HDMI ports... Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: Detected HDMI ports: HDMI 0 (HDMI-1) Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:14 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:15 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: X display number found: 0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 20 14:37:16 volumiopi3 volumio[1166]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 20 14:37:16 volumiopi3 volumio[1166]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 14:37:16 volumiopi3 volumio[1166]: Error: connect EISCONN /tmp/.X11-unix/X0 Dec 20 14:37:16 volumiopi3 volumio[1166]: at PipeConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 20 14:37:16 volumiopi3 volumio[1166]: errno: -106, Dec 20 14:37:16 volumiopi3 volumio[1166]: code: 'EISCONN', Dec 20 14:37:16 volumiopi3 volumio[1166]: syscall: 'connect', Dec 20 14:37:16 volumiopi3 volumio[1166]: address: '/tmp/.X11-unix/X0' Dec 20 14:37:16 volumiopi3 volumio[1166]: } Dec 20 14:37:16 volumiopi3 volumio[1166]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 14:37:20 volumiopi3 sudo[4699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 14:36' Dec 20 14:37:20 volumiopi3 sudo[4699]: 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="5250573efb99f2852fa27ea7e92def8586093039" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="d9e2b6784b9276d5a676bf564ce7524ad160509b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 19 15:53:15 UTC 2025" VOLUMIO_VERSION="4.082" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="47d7740e8c55792fe06a847eb7ad6b29"