Mar 04 08:46:03 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:46:13 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:46:13 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:46:13 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:46:17 volumiokeuken go-librespot[552301]: time="2026-03-04T08:46:17+01:00" level=trace msg="sent dealer ping" Mar 04 08:46:17 volumiokeuken go-librespot[552301]: time="2026-03-04T08:46:17+01:00" level=trace msg="received dealer pong" Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Mar 04 08:46:17 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Mar 04 08:46:17 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Mar 04 08:46:17 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Mar 04 08:46:17 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Mar 04 08:46:17 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Mar 04 08:46:17 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Mar 04 08:46:17 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Mar 04 08:46:17 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ------------------------------ 4ms Mar 04 08:46:17 volumiokeuken volumio[108051]: info: sendMpdCommand status took 5 milliseconds Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ------------------------------ 5ms Mar 04 08:46:17 volumiokeuken volumio[108051]: info: sendMpdCommand status took 5 milliseconds Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ------------------------------ 4ms Mar 04 08:46:17 volumiokeuken volumio[108051]: info: sendMpdCommand status took 4 milliseconds Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ------------------------------ 4ms Mar 04 08:46:17 volumiokeuken volumio[108051]: info: sendMpdCommand status took 4 milliseconds Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:46:17 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 08:46:17 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 08:46:17 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 08:46:17 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2925040,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Studio Brussel","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2925040,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Studio Brussel","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2925040,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Studio Brussel","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2925040,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Studio Brussel","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:46:17 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:46:17 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:17 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.459+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2975999 volume=32 Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.460+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.461+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2975999 volume=32 Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.461+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.461+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2975999 volume=32 Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.461+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2975999 volume=32 Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.461+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2975999 volume=32 Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.462+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2975999 volume=32 Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.462+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2975999 volume=32 Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.462+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.463+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.463+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.463+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.464+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2975999 volume=32 Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.464+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:46:17 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:17.464+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ------------------------------ 35ms Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ------------------------------ 36ms Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ------------------------------ 35ms Mar 04 08:46:17 volumiokeuken volumio[108051]: info: ------------------------------ 35ms Mar 04 08:46:17 volumiokeuken volumio[108051]: info: [LastFM] Current track has sufficient metadata: title (Studio Brussel) and artist (VRT Studio Brussel) passed on explicitly Mar 04 08:46:17 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:17 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:17 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:17 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:17 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:17 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:17 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:17 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:20 volumiokeuken volumio[108051]: error: [LastFM] track info request failed with error: Track not found Mar 04 08:46:20 volumiokeuken systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 04 08:46:20 volumiokeuken systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 04 08:46:23 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:46:28 volumiokeuken go-librespot[552301]: time="2026-03-04T08:46:28+01:00" level=trace msg="received accesspoint ping" Mar 04 08:46:28 volumiokeuken go-librespot[552301]: time="2026-03-04T08:46:28+01:00" level=trace msg="received accesspoint pong ack" Mar 04 08:46:36 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:46:36 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:46:36 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Mar 04 08:46:40 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Mar 04 08:46:40 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Mar 04 08:46:40 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Mar 04 08:46:40 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Mar 04 08:46:40 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Mar 04 08:46:40 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Mar 04 08:46:40 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Mar 04 08:46:40 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ------------------------------ 4ms Mar 04 08:46:40 volumiokeuken volumio[108051]: info: sendMpdCommand status took 4 milliseconds Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ------------------------------ 4ms Mar 04 08:46:40 volumiokeuken volumio[108051]: info: sendMpdCommand status took 3 milliseconds Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ------------------------------ 3ms Mar 04 08:46:40 volumiokeuken volumio[108051]: info: sendMpdCommand status took 3 milliseconds Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ------------------------------ 2ms Mar 04 08:46:40 volumiokeuken volumio[108051]: info: sendMpdCommand status took 2 milliseconds Mar 04 08:46:40 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 08:46:40 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 08:46:40 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2948288,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Life is Music","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2948288,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Life is Music","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2948288,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Life is Music","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.767+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2999278 volume=32 Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.768+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2999278 volume=32 Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.769+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2999278 volume=32 Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.769+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2999278 volume=32 Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.769+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2999278 volume=32 Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.769+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2999278 volume=32 Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.771+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Life is Music" Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.772+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Life is Music" Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.772+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Life is Music" Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.772+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Life is Music" Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.772+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Life is Music" Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.772+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Life is Music" Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ------------------------------ 20ms Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ------------------------------ 21ms Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ------------------------------ 20ms Mar 04 08:46:40 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2948288,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Life is Music","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:46:40 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:46:40 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:46:40 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.785+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2999278 volume=32 Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.785+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Life is Music" Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.785+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=2999278 volume=32 Mar 04 08:46:40 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:46:40.785+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Life is Music" Mar 04 08:46:40 volumiokeuken volumio[108051]: info: ------------------------------ 31ms Mar 04 08:46:40 volumiokeuken volumio[108051]: info: [LastFM] Current track has sufficient metadata: title (Life is Music) and artist (VRT Studio Brussel) passed on explicitly Mar 04 08:46:40 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:40 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:40 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:40 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:40 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:40 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:40 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:40 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:46:43 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:46:44 volumiokeuken volumio[108051]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Life is Music","url":"https://www.last.fm/music/VRT+Studio+Brussel/_/Life+is+Music","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"2","playcount":"78","artist":{"name":"VRT Studio Brussel","url":"https://www.last.fm/music/VRT+Studio+Brussel"},"userplaycount":"0","userloved":"0","toptags":""}} Mar 04 08:46:44 volumiokeuken volumio[108051]: info: [LastFM] Updated missing track duration: 0 Mar 04 08:46:47 volumiokeuken go-librespot[552301]: time="2026-03-04T08:46:47+01:00" level=trace msg="sent dealer ping" Mar 04 08:46:47 volumiokeuken go-librespot[552301]: time="2026-03-04T08:46:47+01:00" level=trace msg="received dealer pong" Mar 04 08:46:53 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:46:53 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:46:53 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Mar 04 08:47:01 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Mar 04 08:47:01 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Mar 04 08:47:01 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Mar 04 08:47:01 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Mar 04 08:47:01 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Mar 04 08:47:01 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Mar 04 08:47:01 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Mar 04 08:47:01 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ------------------------------ 2ms Mar 04 08:47:01 volumiokeuken volumio[108051]: info: sendMpdCommand status took 3 milliseconds Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ------------------------------ 3ms Mar 04 08:47:01 volumiokeuken volumio[108051]: info: sendMpdCommand status took 3 milliseconds Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ------------------------------ 3ms Mar 04 08:47:01 volumiokeuken volumio[108051]: info: sendMpdCommand status took 3 milliseconds Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ------------------------------ 2ms Mar 04 08:47:01 volumiokeuken volumio[108051]: info: sendMpdCommand status took 2 milliseconds Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:47:01 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 08:47:01 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 08:47:01 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 08:47:01 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2968538,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eva & Dries","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2968538,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eva & Dries","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2968538,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eva & Dries","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2968538,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eva & Dries","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:47:01 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:47:01 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:01 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.016+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=3019549 volume=32 Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.018+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=3019549 volume=32 Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.016+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=3019549 volume=32 Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.019+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=3019549 volume=32 Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.019+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=3019549 volume=32 Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.019+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Eva & Dries" Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.019+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Eva & Dries" Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.020+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=3019549 volume=32 Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.021+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Eva & Dries" Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.021+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=3019549 volume=32 Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.021+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" state=STATUS_PLAYING positionMs=3019549 volume=32 Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.025+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Eva & Dries" Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.026+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Eva & Dries" Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.026+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Eva & Dries" Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.026+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Eva & Dries" Mar 04 08:47:01 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:01.026+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:45792 @ 0xc0003205d0" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Eva & Dries" Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ------------------------------ 29ms Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ------------------------------ 29ms Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ------------------------------ 29ms Mar 04 08:47:01 volumiokeuken volumio[108051]: info: ------------------------------ 28ms Mar 04 08:47:01 volumiokeuken volumio[108051]: info: [LastFM] Current track has sufficient metadata: title (Eva & Dries) and artist (VRT Studio Brussel) passed on explicitly Mar 04 08:47:01 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:01 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:01 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:01 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:01 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:01 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:01 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:01 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:05 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:47:11 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:11.520+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.19:45792 Mar 04 08:47:11 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:11.520+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.19:45792 Mar 04 08:47:11 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:11.813+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.19:55364 Mar 04 08:47:13 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:47:13 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:47:13 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:47:17 volumiokeuken go-librespot[552301]: time="2026-03-04T08:47:17+01:00" level=trace msg="sent dealer ping" Mar 04 08:47:17 volumiokeuken go-librespot[552301]: time="2026-03-04T08:47:17+01:00" level=trace msg="received dealer pong" Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Mar 04 08:47:21 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Mar 04 08:47:21 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Mar 04 08:47:21 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Mar 04 08:47:21 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Mar 04 08:47:21 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Mar 04 08:47:21 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Mar 04 08:47:21 volumiokeuken volumio[108051]: ---------------------------- MPD announces system playlist update Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Ignoring MPD Status Update Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Mar 04 08:47:21 volumiokeuken volumio[108051]: ---------------------------- MPD announces state update: player Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ControllerMpd::getState Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand status Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ------------------------------ 3ms Mar 04 08:47:21 volumiokeuken volumio[108051]: info: sendMpdCommand status took 2 milliseconds Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ------------------------------ 3ms Mar 04 08:47:21 volumiokeuken volumio[108051]: info: sendMpdCommand status took 2 milliseconds Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ------------------------------ 2ms Mar 04 08:47:21 volumiokeuken volumio[108051]: info: sendMpdCommand status took 2 milliseconds Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ------------------------------ 4ms Mar 04 08:47:21 volumiokeuken volumio[108051]: info: sendMpdCommand status took 4 milliseconds Mar 04 08:47:21 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 08:47:21 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 08:47:21 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseState Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2988786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Studio Brussel","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2988786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Studio Brussel","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2988786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Studio Brussel","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.266+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" state=STATUS_PLAYING positionMs=3039824 volume=32 Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.266+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.270+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" state=STATUS_PLAYING positionMs=3039824 volume=32 Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.270+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" state=STATUS_PLAYING positionMs=3039824 volume=32 Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.271+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.271+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" state=STATUS_PLAYING positionMs=3039824 volume=32 Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.271+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.271+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.271+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" state=STATUS_PLAYING positionMs=3039824 volume=32 Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.272+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.272+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" state=STATUS_PLAYING positionMs=3039824 volume=32 Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.272+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ------------------------------ 26ms Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ------------------------------ 25ms Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ------------------------------ 25ms Mar 04 08:47:21 volumiokeuken volumio[108051]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: ControllerMpd::parseTrackInfo Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ControllerMpd::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::servicePushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CorePlayQueue::getTrack 0 Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2988786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Studio Brussel","artist":"VRT Studio Brussel","album":null,"uri":"http://icecast.vrtcdn.be/stubru-high.mp3/tunein","trackType":"mp3/tunein"} Mar 04 08:47:21 volumiokeuken volumio[108051]: verbose: CURRENT POSITION 0 Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState stateService play Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::syncState currentStatus play Mar 04 08:47:21 volumiokeuken volumio[108051]: info: Received an update from plugin. extracting info from payload Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreStateMachine::pushState Mar 04 08:47:21 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioPushState Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.278+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" state=STATUS_PLAYING positionMs=3039824 volume=32 Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.278+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:47:21 volumiokeuken volumio[108051]: info: ------------------------------ 30ms Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.281+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" state=STATUS_PLAYING positionMs=3039824 volume=32 Mar 04 08:47:21 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:21.281+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.19:55364 @ 0xc00035e000" id=http://icecast.vrtcdn.be/stubru-high.mp3/tunein title="Studio Brussel" Mar 04 08:47:21 volumiokeuken volumio[108051]: info: [LastFM] Current track has sufficient metadata: title (Studio Brussel) and artist (VRT Studio Brussel) passed on explicitly Mar 04 08:47:21 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:21 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:21 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:21 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:21 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:21 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:21 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:21 volumiokeuken volumio[108051]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 04 08:47:21 volumiokeuken volumio[108051]: error: [LastFM] track info request failed with error: Track not found Mar 04 08:47:24 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:47:27 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:27.449+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.19:55364 Mar 04 08:47:27 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:27.449+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.19:55364 Mar 04 08:47:30 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:30.652+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.19:44554 Mar 04 08:47:33 volumiokeuken volumio[108051]: info: CoreCommandRouter::volumioGetState Mar 04 08:47:33 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:47:33 volumiokeuken volumio[108051]: info: Listing playlists Mar 04 08:47:33 volumiokeuken volumio5-onboarding[2196]: time=2026-03-04T08:47:33.489+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.19:53192 Mar 04 08:47:40 volumiokeuken volumio[108051]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 08:47:40 volumiokeuken volumio[108051]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Mar 04 08:47:40 volumiokeuken volumio[108051]: at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:118:26) { Mar 04 08:47:40 volumiokeuken volumio[108051]: errno: -3007, Mar 04 08:47:40 volumiokeuken volumio[108051]: code: 'ENOTFOUND', Mar 04 08:47:40 volumiokeuken volumio[108051]: syscall: 'getaddrinfo', Mar 04 08:47:40 volumiokeuken volumio[108051]: hostname: 'ws.audioscrobbler.com' Mar 04 08:47:40 volumiokeuken volumio[108051]: } Mar 04 08:47:40 volumiokeuken volumio[108051]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 08:47:43 volumiokeuken sudo[791522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 08:46' Mar 04 08:47:43 volumiokeuken sudo[791522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"