Mar 24 13:34:08 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Mar 24 13:34:08 volumio kernel: vc4-drm gpu: [drm] *ERROR* Timed out waiting for commit Mar 24 13:34:08 volumio volumio[3228]: info: CoreCommandRouter::volumioPrevious Mar 24 13:34:08 volumio volumio[3228]: info: CoreStateMachine::previous Mar 24 13:34:08 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:08 volumio volumio[3228]: info: [1774355648957] ControllerWebradio::seek Mar 24 13:34:08 volumio volumio[3228]: info: ControllerMpd::seek Mar 24 13:34:09 volumio volumio[3228]: error: null Mar 24 13:34:09 volumio volumio[3228]: info: Mar 24 13:34:09 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:34:09 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:34:09 volumio volumio[3228]: info: Mar 24 13:34:09 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:34:09 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:34:09 volumio volumio[3228]: info: sendMpdCommand status took 4 milliseconds Mar 24 13:34:09 volumio volumio[3228]: info: sendMpdCommand status took 4 milliseconds Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:34:09 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 13:34:09 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:34:09 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:34:09 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:09 volumio volumio[3228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"De Dijk - Ik kan het niet alleen","artist":"NPO Radio2","album":null,"uri":"https://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Mar 24 13:34:09 volumio volumio[3228]: verbose: CURRENT POSITION 0 Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::syncState stateService play Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:34:09 volumio volumio[3228]: info: Received an update from plugin. extracting info from payload Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:09 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:34:09 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:09 volumio volumio[3228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"De Dijk - Ik kan het niet alleen","artist":"NPO Radio2","album":null,"uri":"https://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Mar 24 13:34:09 volumio volumio[3228]: verbose: CURRENT POSITION 0 Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::syncState stateService play Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:34:09 volumio volumio[3228]: info: Received an update from plugin. extracting info from payload Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.525Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=500 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.525Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=500 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.525Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=500 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.525Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=500 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.525Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=500 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.526Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.526Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.527Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=500 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.527Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=500 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.527Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.528Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.530Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=500 volume=17 Mar 24 13:34:09 volumio volumio[3228]: info: ------------------------------ 31ms Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.531Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.531Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:09 volumio volumio[3228]: info: ------------------------------ 31ms Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.533Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.534Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:09 volumio volumio[3228]: info: Mar 24 13:34:09 volumio volumio[3228]: ---------------------------- MPD announces system playlist update Mar 24 13:34:09 volumio volumio[3228]: info: Ignoring MPD Status Update Mar 24 13:34:09 volumio volumio[3228]: info: Mar 24 13:34:09 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:34:09 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:34:09 volumio volumio[3228]: info: Mar 24 13:34:09 volumio volumio[3228]: ---------------------------- MPD announces system playlist update Mar 24 13:34:09 volumio volumio[3228]: info: Ignoring MPD Status Update Mar 24 13:34:09 volumio volumio[3228]: info: Mar 24 13:34:09 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:34:09 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:34:09 volumio volumio[3228]: info: ------------------------------ 5ms Mar 24 13:34:09 volumio volumio[3228]: info: sendMpdCommand status took 9 milliseconds Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:34:09 volumio volumio[3228]: info: ------------------------------ 15ms Mar 24 13:34:09 volumio volumio[3228]: info: sendMpdCommand status took 14 milliseconds Mar 24 13:34:09 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:34:09 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:34:09 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:09 volumio volumio[3228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio2-bb-mp3","artist":"NPO Radio2","album":null,"uri":"https://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Mar 24 13:34:09 volumio volumio[3228]: verbose: CURRENT POSITION 0 Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::syncState stateService play Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:34:09 volumio volumio[3228]: info: Received an update from plugin. extracting info from payload Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.582Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=757 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.582Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=757 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.582Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=757 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.582Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=757 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.582Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.583Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.583Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.584Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:09 volumio volumio[3228]: info: ------------------------------ 34ms Mar 24 13:34:09 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 13:34:09 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:34:09 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:34:09 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:09 volumio volumio[3228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio2-bb-mp3","artist":"NPO Radio2","album":null,"uri":"https://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Mar 24 13:34:09 volumio volumio[3228]: verbose: CURRENT POSITION 0 Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::syncState stateService play Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:34:09 volumio volumio[3228]: info: Received an update from plugin. extracting info from payload Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:09 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.595Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=757 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.596Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=757 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.596Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.595Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=757 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.596Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.596Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=757 volume=17 Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.597Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:09 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:09.597Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:09 volumio volumio[3228]: info: ------------------------------ 43ms Mar 24 13:34:10 volumio volumio[3228]: info: Mar 24 13:34:10 volumio volumio[3228]: ---------------------------- MPD announces system playlist update Mar 24 13:34:10 volumio volumio[3228]: info: Ignoring MPD Status Update Mar 24 13:34:10 volumio volumio[3228]: info: Mar 24 13:34:10 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:34:10 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:34:10 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:34:10 volumio volumio[3228]: info: Mar 24 13:34:10 volumio volumio[3228]: ---------------------------- MPD announces system playlist update Mar 24 13:34:10 volumio volumio[3228]: info: Ignoring MPD Status Update Mar 24 13:34:10 volumio volumio[3228]: info: Mar 24 13:34:10 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:34:10 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:34:10 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:34:10 volumio volumio[3228]: info: ------------------------------ 2ms Mar 24 13:34:10 volumio volumio[3228]: info: sendMpdCommand status took 3 milliseconds Mar 24 13:34:10 volumio volumio[3228]: info: ------------------------------ 2ms Mar 24 13:34:10 volumio volumio[3228]: info: sendMpdCommand status took 2 milliseconds Mar 24 13:34:10 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:34:10 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:34:10 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:34:10 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:34:10 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 24 13:34:10 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 24 13:34:10 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:34:10 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:34:10 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:34:10 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:34:10 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:10 volumio volumio[3228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"De Dijk - Ik kan het niet alleen","artist":"NPO Radio2","album":null,"uri":"https://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Mar 24 13:34:10 volumio volumio[3228]: verbose: CURRENT POSITION 0 Mar 24 13:34:10 volumio volumio[3228]: info: CoreStateMachine::syncState stateService play Mar 24 13:34:10 volumio volumio[3228]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:34:10 volumio volumio[3228]: info: Received an update from plugin. extracting info from payload Mar 24 13:34:10 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:10 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:34:10 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:10 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:10 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:10 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:34:10 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:34:10 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:10 volumio volumio[3228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"De Dijk - Ik kan het niet alleen","artist":"NPO Radio2","album":null,"uri":"https://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Mar 24 13:34:10 volumio volumio[3228]: verbose: CURRENT POSITION 0 Mar 24 13:34:10 volumio volumio[3228]: info: CoreStateMachine::syncState stateService play Mar 24 13:34:10 volumio volumio[3228]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:34:10 volumio volumio[3228]: info: Received an update from plugin. extracting info from payload Mar 24 13:34:10 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:10 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:10 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:10 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.705Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=1758 volume=17 Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.705Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=1758 volume=17 Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.705Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=1758 volume=17 Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.706Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.706Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.706Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=1758 volume=17 Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.706Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=1758 volume=17 Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.706Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=1758 volume=17 Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.706Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=1758 volume=17 Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.707Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.707Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.707Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=1758 volume=17 Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.708Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.709Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.709Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:10.710Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="De Dijk - Ik kan het niet alleen" Mar 24 13:34:10 volumio volumio[3228]: info: ------------------------------ 23ms Mar 24 13:34:10 volumio volumio[3228]: info: ------------------------------ 22ms Mar 24 13:34:11 volumio volumio[3228]: info: CoreCommandRouter::volumioPrevious Mar 24 13:34:11 volumio volumio[3228]: info: CoreStateMachine::previous Mar 24 13:34:11 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:15 volumio volumio[3228]: info: CoreCommandRouter::volumioGetQueue Mar 24 13:34:15 volumio volumio[3228]: info: CoreStateMachine::getQueue Mar 24 13:34:15 volumio volumio[3228]: info: CorePlayQueue::getQueue Mar 24 13:34:18 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:102:pixelvalve-2] flip_done timed out Mar 24 13:34:22 volumio volumio[3228]: info: CoreCommandRouter::volumioGetState Mar 24 13:34:27 volumio volumio[3228]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 13:34:28 volumio volumio[3228]: info: Getting Alsa Cards List without I2S DAC Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 24 13:34:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 24 13:34:29 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Mar 24 13:34:29 volumio kernel: vc4-drm gpu: [drm] *ERROR* Timed out waiting for commit Mar 24 13:34:34 volumio ntpd[1087]: PROTO: 5.101.84.95 unlink local addr 192.168.1.238 -> Mar 24 13:34:40 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Mar 24 13:34:40 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:102:pixelvalve-2] commit wait timed out Mar 24 13:34:50 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Mar 24 13:34:50 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CONNECTOR:33:HDMI-A-1] commit wait timed out Mar 24 13:34:50 volumio volumio[3228]: info: CoreCommandRouter::volumioStop Mar 24 13:34:50 volumio volumio[3228]: info: CoreStateMachine::stop Mar 24 13:34:50 volumio volumio[3228]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 13:34:50 volumio volumio[3228]: info: CoreStateMachine::stPlaybackTimer Mar 24 13:34:50 volumio volumio[3228]: info: CoreStateMachine::updateTrackBlock Mar 24 13:34:50 volumio volumio[3228]: info: CorePlayQueue::getTrackBlock Mar 24 13:34:50 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:50 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:50 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:34:50 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:50 volumio volumio[3228]: info: CoreStateMachine::serviceStop Mar 24 13:34:50 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:50 volumio volumio[3228]: info: CoreCommandRouter::serviceStop Mar 24 13:34:50 volumio volumio[3228]: info: [1774355690344] ControllerWebradio::stop Mar 24 13:34:50 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.346Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_STOPPED positionMs=0 volume=17 Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.347Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_STOPPED positionMs=0 volume=17 Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.347Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.348Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:50 volumio volumio[3228]: info: Mar 24 13:34:50 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:34:50 volumio volumio[3228]: info: sendMpdCommand stop took 27 milliseconds Mar 24 13:34:50 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:34:50 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:34:50 volumio volumio[3228]: info: Mar 24 13:34:50 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:34:50 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:34:50 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:34:50 volumio volumio[3228]: info: sendMpdCommand status took 3 milliseconds Mar 24 13:34:50 volumio volumio[3228]: info: sendMpdCommand status took 2 milliseconds Mar 24 13:34:50 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:34:50 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:34:50 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:34:50 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:34:50 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 13:34:50 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 24 13:34:50 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:34:50 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:34:50 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:34:50 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:34:50 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:50 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:50 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:50 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:50 volumio volumio[3228]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 24 13:34:50 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:34:50 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:34:50 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:50 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:50 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:50 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:50 volumio volumio[3228]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.387Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_STOPPED positionMs=0 volume=17 Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.387Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_STOPPED positionMs=0 volume=17 Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.387Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_STOPPED positionMs=0 volume=17 Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.388Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_STOPPED positionMs=0 volume=17 Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.388Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.388Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.389Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:50 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:50.389Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:50 volumio volumio[3228]: info: ------------------------------ 19ms Mar 24 13:34:50 volumio volumio[3228]: info: ------------------------------ 18ms Mar 24 13:34:56 volumio volumio[3228]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 24 13:34:56 volumio volumio[3228]: info: Preparing to save Alsa Options, stopping services first Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::volumioGetState Mar 24 13:34:56 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::volumioStop Mar 24 13:34:56 volumio volumio[3228]: info: CoreStateMachine::stop Mar 24 13:34:56 volumio volumio[3228]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 13:34:56 volumio volumio[3228]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dacplus","label":"HiFiBerry Amp2"}} Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry Amp2 Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 24 13:34:56 volumio volumio[3228]: info: Updating Volume Controller Parameters: Device: 4 Name: HiFiBerry Amp2 Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 24 13:34:56 volumio volumio[3228]: info: Disabling external Volume Control Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 13:34:56 volumio volumio[3228]: info: Preparing to generate the ALSA configuration file Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 13:34:56 volumio volumio[3228]: info: Getting Alsa Cards List without I2S DAC Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 24 13:34:56 volumio volumio[3228]: info: Asound.conf file unchanged, so no further update is needed Mar 24 13:34:56 volumio volumio[3228]: info: Output device has changed, restarting MPD Mar 24 13:34:56 volumio volumio[3228]: info: Output device has changed, restarting Shairport Sync Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:34:56 volumio sudo[8753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 13:34:56 volumio sudo[8753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:34:56 volumio sudo[8753]: pam_unix(sudo:session): session closed for user root Mar 24 13:34:56 volumio sudo[8755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 13:34:56 volumio sudo[8755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:34:56 volumio volumio[3228]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 13:34:56 volumio volumio[3228]: info: VolumeController:: Volume=100 Mute =false Mar 24 13:34:56 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:34:56 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:34:56 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:56.622Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_STOPPED positionMs=502 volume=100 Mar 24 13:34:56 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:56.623Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_STOPPED positionMs=502 volume=100 Mar 24 13:34:56 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:56.623Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:56 volumio volumio5-onboarding[1703]: time=2026-03-24T12:34:56.623Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:34:56 volumio volumio[3228]: info: MPD Permissions set Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 13:34:56 volumio volumio[3228]: info: Starting Shairport Sync Mar 24 13:34:56 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 24 13:34:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 24 13:34:56 volumio systemd[1]: mpd.service: Consumed 8.262s CPU time. Mar 24 13:34:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 24 13:34:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 24 13:34:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 24 13:34:56 volumio sudo[8768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 13:34:56 volumio sudo[8768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:34:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 24 13:34:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 13:34:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 24 13:34:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 24 13:34:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 13:34:56 volumio systemd[1]: shairport-sync.service: Consumed 2.729s CPU time. Mar 24 13:34:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 13:34:56 volumio sudo[8768]: pam_unix(sudo:session): session closed for user root Mar 24 13:34:56 volumio volumio[3228]: info: Shairport-Sync Started Mar 24 13:34:57 volumio sudo[8771]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 13:34:57 volumio sudo[8771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 13:34:57 volumio sudo[8771]: pam_unix(sudo:session): session closed for user root Mar 24 13:34:59 volumio mpd[8788]: 2026-03-24T13:34:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 24 13:34:59 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 24 13:34:59 volumio sudo[8755]: pam_unix(sudo:session): session closed for user root Mar 24 13:34:59 volumio volumio[3228]: error: updateQueue error: null Mar 24 13:35:00 volumio volumio[3228]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 24 13:35:00 volumio volumio[3228]: info: CoreStateMachine::getcurrentVolume Mar 24 13:35:00 volumio volumio[3228]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 13:35:00 volumio volumio[3228]: info: VolumeController:: Volume=100 Mute =false Mar 24 13:35:00 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:35:00 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:00 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:35:00 volumio volumio[3228]: info: CoreStateMachine::updateTrackBlock Mar 24 13:35:00 volumio volumio[3228]: info: CorePlayQueue::getTrackBlock Mar 24 13:35:00 volumio volumio[3228]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 13:35:00 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:00.328Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_STOPPED positionMs=502 volume=100 Mar 24 13:35:00 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:00.329Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_STOPPED positionMs=502 volume=100 Mar 24 13:35:00 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:00.329Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:00 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:00.329Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:00 volumio volumio[3228]: info: VolumeController:: Volume=100 Mute =false Mar 24 13:35:00 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:35:00 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:00 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:35:00 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:00.358Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_STOPPED positionMs=502 volume=100 Mar 24 13:35:00 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:00.359Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_STOPPED positionMs=502 volume=100 Mar 24 13:35:00 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:00.359Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:00 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:00.360Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:00 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:102:pixelvalve-2] flip_done timed out Mar 24 13:35:06 volumio volumio[3228]: info: CoreCommandRouter::volumioGetState Mar 24 13:35:06 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:07 volumio volumio[3228]: info: CoreCommandRouter::volumioPlay Mar 24 13:35:07 volumio volumio[3228]: info: CoreStateMachine::play index undefined Mar 24 13:35:07 volumio volumio[3228]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 13:35:07 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:07 volumio volumio[3228]: info: CoreStateMachine::startPlaybackTimer Mar 24 13:35:07 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:07 volumio volumio[3228]: info: [1774355707790] ControllerWebradio::clearAddPlayTrack Mar 24 13:35:07 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 13:35:07 volumio volumio[3228]: info: sendMpdCommand stop took 1 milliseconds Mar 24 13:35:07 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 13:35:07 volumio volumio[3228]: info: Mar 24 13:35:07 volumio volumio[3228]: ---------------------------- MPD announces system playlist update Mar 24 13:35:07 volumio volumio[3228]: info: Ignoring MPD Status Update Mar 24 13:35:07 volumio volumio[3228]: info: sendMpdCommand clear took 1 milliseconds Mar 24 13:35:07 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand load "https://icecast.omroep.nl/radio2-bb-mp3" Mar 24 13:35:07 volumio volumio[3228]: error: updateQueue error: null Mar 24 13:35:07 volumio volumio[3228]: info: ------------------------------ 3ms Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand add "https://icecast.omroep.nl/radio2-bb-mp3" Mar 24 13:35:08 volumio volumio[3228]: info: Mar 24 13:35:08 volumio volumio[3228]: ---------------------------- MPD announces system playlist update Mar 24 13:35:08 volumio volumio[3228]: info: Ignoring MPD Status Update Mar 24 13:35:08 volumio volumio[3228]: info: sendMpdCommand add "https://icecast.omroep.nl/radio2-bb-mp3" took 2 milliseconds Mar 24 13:35:08 volumio volumio[3228]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand play Mar 24 13:35:08 volumio volumio[3228]: info: ------------------------------ 3ms Mar 24 13:35:08 volumio volumio[3228]: info: sendMpdCommand play took 3 milliseconds Mar 24 13:35:08 volumio volumio[3228]: info: Mar 24 13:35:08 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:35:08 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:35:08 volumio volumio[3228]: info: Mar 24 13:35:08 volumio volumio[3228]: ---------------------------- MPD announces system playlist update Mar 24 13:35:08 volumio volumio[3228]: info: Ignoring MPD Status Update Mar 24 13:35:08 volumio volumio[3228]: info: Mar 24 13:35:08 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:35:08 volumio volumio[3228]: info: sendMpdCommand status took 40 milliseconds Mar 24 13:35:08 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:35:08 volumio volumio[3228]: info: ------------------------------ 4ms Mar 24 13:35:08 volumio volumio[3228]: info: sendMpdCommand status took 3 milliseconds Mar 24 13:35:08 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:35:08 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:35:08 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:35:08 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:08 volumio volumio[3228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio2-bb-mp3","artist":"NPO Radio2","album":null,"uri":"https://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Mar 24 13:35:08 volumio volumio[3228]: verbose: CURRENT POSITION 0 Mar 24 13:35:08 volumio volumio[3228]: info: CoreStateMachine::syncState stateService play Mar 24 13:35:08 volumio volumio[3228]: info: CoreStateMachine::syncState currentStatus stop Mar 24 13:35:08 volumio volumio[3228]: info: ------------------------------ 47ms Mar 24 13:35:08 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 13:35:08 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:35:08 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:35:08 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:35:08 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:08 volumio volumio[3228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":284,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"radio2-bb-mp3","artist":"NPO Radio2","album":null,"uri":"https://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Mar 24 13:35:08 volumio volumio[3228]: verbose: CURRENT POSITION 0 Mar 24 13:35:08 volumio volumio[3228]: info: CoreStateMachine::syncState stateService play Mar 24 13:35:08 volumio volumio[3228]: info: CoreStateMachine::syncState currentStatus play Mar 24 13:35:08 volumio volumio[3228]: info: Received an update from plugin. extracting info from payload Mar 24 13:35:08 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:35:08 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:08 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:35:08 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:35:08 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:35:08 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:35:08 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:08.883Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 13:35:08 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:08.883Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 13:35:08 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:08.884Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:08 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:08.884Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:08 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:08.885Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 13:35:08 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:08.885Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 13:35:08 volumio volumio[3228]: info: ------------------------------ 18ms Mar 24 13:35:08 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:08.886Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:08 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:08.886Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:10 volumio volumio[3228]: info: CoreCommandRouter::volumioStop Mar 24 13:35:10 volumio volumio[3228]: info: CoreStateMachine::stop Mar 24 13:35:10 volumio volumio[3228]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 13:35:10 volumio volumio[3228]: info: CoreStateMachine::stPlaybackTimer Mar 24 13:35:10 volumio volumio[3228]: info: CoreStateMachine::updateTrackBlock Mar 24 13:35:10 volumio volumio[3228]: info: CorePlayQueue::getTrackBlock Mar 24 13:35:10 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:35:10 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:10 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 13:35:10 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:35:10 volumio volumio[3228]: info: CoreStateMachine::serviceStop Mar 24 13:35:10 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:10 volumio volumio[3228]: info: CoreCommandRouter::serviceStop Mar 24 13:35:10 volumio volumio[3228]: info: [1774355710075] ControllerWebradio::stop Mar 24 13:35:10 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 13:35:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:10.077Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 13:35:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:10.078Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 13:35:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:10.079Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:10.080Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:10 volumio volumio[3228]: info: Mar 24 13:35:10 volumio volumio[3228]: ---------------------------- MPD announces state update: player Mar 24 13:35:10 volumio volumio[3228]: info: sendMpdCommand stop took 28 milliseconds Mar 24 13:35:10 volumio volumio[3228]: info: ControllerMpd::getState Mar 24 13:35:10 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand status Mar 24 13:35:10 volumio volumio[3228]: info: sendMpdCommand status took 2 milliseconds Mar 24 13:35:10 volumio volumio[3228]: verbose: ControllerMpd::parseState Mar 24 13:35:10 volumio volumio[3228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 13:35:10 volumio volumio[3228]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 24 13:35:10 volumio volumio[3228]: verbose: ControllerMpd::parseTrackInfo Mar 24 13:35:10 volumio volumio[3228]: info: ControllerMpd::pushState Mar 24 13:35:10 volumio volumio[3228]: info: CoreCommandRouter::servicePushState Mar 24 13:35:10 volumio volumio[3228]: info: CoreStateMachine::pushState Mar 24 13:35:10 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:10 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Mar 24 13:35:10 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:10 volumio volumio[3228]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 24 13:35:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:10.116Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 13:35:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:10.116Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 13:35:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:10.117Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.186:59902 @ 0x1c00450" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:10 volumio volumio5-onboarding[1703]: time=2026-03-24T12:35:10.117Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:55042 @ 0x1e52180" id=https://icecast.omroep.nl/radio2-bb-mp3 title="NPO Radio 2" Mar 24 13:35:10 volumio volumio[3228]: info: ------------------------------ 14ms Mar 24 13:35:10 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Mar 24 13:35:10 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:102:pixelvalve-2] commit wait timed out Mar 24 13:35:21 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Mar 24 13:35:21 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CONNECTOR:33:HDMI-A-1] commit wait timed out Mar 24 13:35:26 volumio volumio[3228]: info: CoreCommandRouter::volumioGetState Mar 24 13:35:26 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Mar 24 13:35:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 13:35:28 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 13:35:31 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Mar 24 13:35:31 volumio kernel: vc4-drm gpu: [drm] *ERROR* Timed out waiting for commit Mar 24 13:35:35 volumio volumio[3228]: info: Starting Uninstall of plugin user_interface - touch_display Mar 24 13:35:35 volumio volumio[3228]: info: Uninstalling plugin touch_display Mar 24 13:35:35 volumio sudo[8863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Mar 24 13:35:35 volumio sudo[8863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:35:35 volumio sudo[8863]: pam_unix(sudo:session): session closed for user root Mar 24 13:35:35 volumio sudo[8865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Mar 24 13:35:35 volumio sudo[8865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:35:35 volumio sudo[8867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Mar 24 13:35:35 volumio sudo[8867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:35:35 volumio volumio[3228]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Mar 24 13:35:35 volumio volumio[3228]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 13:35:35 volumio volumio[3228]: TypeError: Cannot read properties of undefined (reading 'split') Mar 24 13:35:35 volumio volumio[3228]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Mar 24 13:35:35 volumio volumio[3228]: at ChildProcess.exithandler (node:child_process:413:7) Mar 24 13:35:35 volumio volumio[3228]: at ChildProcess.emit (node:events:514:28) Mar 24 13:35:35 volumio volumio[3228]: at maybeClose (node:internal/child_process:1105:16) Mar 24 13:35:35 volumio volumio[3228]: at Socket. (node:internal/child_process:457:11) Mar 24 13:35:35 volumio volumio[3228]: at Socket.emit (node:events:514:28) Mar 24 13:35:35 volumio volumio[3228]: at Pipe. (node:net:337:12) Mar 24 13:35:35 volumio volumio[3228]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 13:35:35 volumio sudo[8871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Mar 24 13:35:35 volumio sudo[8871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 13:35:35 volumio systemd[1]: Reloading. Mar 24 13:35:36 volumio sudo[8905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 13:34' Mar 24 13:35:36 volumio sudo[8905]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"