Aug 31 22:35:00 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:01 volumiobouix volumio[1236]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object] Aug 31 22:35:01 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions Aug 31 22:35:04 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:08 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:12 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:13 volumiobouix volumio[1236]: info: Aug 31 22:35:13 volumiobouix volumio[1236]: ---------------------------- MPD announces system playlist update Aug 31 22:35:13 volumiobouix volumio[1236]: info: Ignoring MPD Status Update Aug 31 22:35:13 volumiobouix volumio[1236]: info: Aug 31 22:35:13 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: player Aug 31 22:35:13 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:13 volumiobouix volumio[1236]: info: Aug 31 22:35:13 volumiobouix volumio[1236]: ---------------------------- MPD announces system playlist update Aug 31 22:35:13 volumiobouix volumio[1236]: info: Ignoring MPD Status Update Aug 31 22:35:13 volumiobouix volumio[1236]: info: Aug 31 22:35:13 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: player Aug 31 22:35:13 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:13 volumiobouix volumio[1236]: info: Aug 31 22:35:13 volumiobouix volumio[1236]: ---------------------------- MPD announces system playlist update Aug 31 22:35:13 volumiobouix volumio[1236]: info: Ignoring MPD Status Update Aug 31 22:35:13 volumiobouix volumio[1236]: info: Aug 31 22:35:13 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: player Aug 31 22:35:13 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:13 volumiobouix volumio[1236]: info: ------------------------------ 4ms Aug 31 22:35:13 volumiobouix volumio[1236]: info: sendMpdCommand status took 4 milliseconds Aug 31 22:35:13 volumiobouix volumio[1236]: info: ------------------------------ 3ms Aug 31 22:35:13 volumiobouix volumio[1236]: info: sendMpdCommand status took 3 milliseconds Aug 31 22:35:13 volumiobouix volumio[1236]: info: ------------------------------ 2ms Aug 31 22:35:13 volumiobouix volumio[1236]: info: sendMpdCommand status took 3 milliseconds Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:13 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 22:35:13 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 22:35:13 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:13 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1484466,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jazz Radio - Latin Jazz - La musique revient vite...","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:13 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1484466,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jazz Radio - Latin Jazz - La musique revient vite...","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:13 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1484466,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jazz Radio - Latin Jazz - La musique revient vite...","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:13 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:13 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:13 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.592+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1483742 volume=39 Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.593+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Jazz Radio - Latin Jazz - La musique revient vite..." Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.595+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1483742 volume=39 Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.596+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Jazz Radio - Latin Jazz - La musique revient vite..." Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.598+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1483742 volume=39 Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.598+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Jazz Radio - Latin Jazz - La musique revient vite..." Aug 31 22:35:13 volumiobouix volumio[1236]: info: ------------------------------ 31ms Aug 31 22:35:13 volumiobouix volumio[1236]: info: ------------------------------ 31ms Aug 31 22:35:13 volumiobouix volumio[1236]: info: ------------------------------ 30ms Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.608+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1483742 volume=39 Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.608+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1483742 volume=39 Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.609+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1483742 volume=39 Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.609+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Jazz Radio - Latin Jazz - La musique revient vite..." Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.609+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Jazz Radio - Latin Jazz - La musique revient vite..." Aug 31 22:35:13 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:13.610+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Jazz Radio - Latin Jazz - La musique revient vite..." Aug 31 22:35:16 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:17 volumiobouix volumio[1236]: info: Aug 31 22:35:17 volumiobouix volumio[1236]: ---------------------------- MPD announces system playlist update Aug 31 22:35:17 volumiobouix volumio[1236]: info: Ignoring MPD Status Update Aug 31 22:35:17 volumiobouix volumio[1236]: info: Aug 31 22:35:17 volumiobouix volumio[1236]: ---------------------------- MPD announces system playlist update Aug 31 22:35:17 volumiobouix volumio[1236]: info: Ignoring MPD Status Update Aug 31 22:35:17 volumiobouix volumio[1236]: info: Aug 31 22:35:17 volumiobouix volumio[1236]: ---------------------------- MPD announces system playlist update Aug 31 22:35:17 volumiobouix volumio[1236]: info: Ignoring MPD Status Update Aug 31 22:35:17 volumiobouix volumio[1236]: info: Aug 31 22:35:17 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: player Aug 31 22:35:17 volumiobouix volumio[1236]: info: ------------------------------ 3ms Aug 31 22:35:17 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:17 volumiobouix volumio[1236]: info: ------------------------------ 2ms Aug 31 22:35:17 volumiobouix volumio[1236]: info: Aug 31 22:35:17 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: player Aug 31 22:35:17 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:17 volumiobouix volumio[1236]: info: Aug 31 22:35:17 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: player Aug 31 22:35:17 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:17 volumiobouix volumio[1236]: info: ------------------------------ 5ms Aug 31 22:35:17 volumiobouix volumio[1236]: info: sendMpdCommand status took 4 milliseconds Aug 31 22:35:17 volumiobouix volumio[1236]: info: sendMpdCommand status took 2 milliseconds Aug 31 22:35:17 volumiobouix volumio[1236]: info: sendMpdCommand status took 1 milliseconds Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:17 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 22:35:17 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 22:35:17 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:17 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1488665,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cortijo Y Su Combo - Tequila","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:17 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1488665,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cortijo Y Su Combo - Tequila","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:17 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1488665,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cortijo Y Su Combo - Tequila","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:17 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:17 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:17 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.834+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1487998 volume=39 Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.835+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.836+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1487998 volume=39 Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.837+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.839+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1487998 volume=39 Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.839+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1487998 volume=39 Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.839+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.839+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.840+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1487998 volume=39 Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.841+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.841+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1487998 volume=39 Aug 31 22:35:17 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:17.841+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:17 volumiobouix volumio[1236]: info: ------------------------------ 32ms Aug 31 22:35:17 volumiobouix volumio[1236]: info: ------------------------------ 30ms Aug 31 22:35:17 volumiobouix volumio[1236]: info: ------------------------------ 30ms Aug 31 22:35:20 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:24 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:26 volumiobouix volumio[1236]: info: CALLMETHOD: music_service mpd saveMusicLibraryOptions [object Object] Aug 31 22:35:26 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , saveMusicLibraryOptions Aug 31 22:35:28 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:32 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:36 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:40 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:44 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:48 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand rescan Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: info: Aug 31 22:35:49 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: update Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand rescan took 3 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: info: Aug 31 22:35:49 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: update Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: info: Aug 31 22:35:49 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: update Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 6 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 6 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: info: Command Router : Notfying DB Updatetrue Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: info: Aug 31 22:35:49 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: update Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 34 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 33 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 34 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 33 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: info: Command Router : Notfying DB Updatetrue Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: info: Command Router : Notfying DB Updatetrue Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1520261,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cortijo Y Su Combo - Tequila","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:49 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.523+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.523+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.524+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.524+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio[1236]: info: ------------------------------ 80ms Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: info: Aug 31 22:35:49 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: update Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: info: Aug 31 22:35:49 volumiobouix volumio[1236]: ---------------------------- MPD announces state update: update Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::getState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 53 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 52 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 35 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 10 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 8 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 8 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand status took 7 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: info: Command Router : Notfying DB Updatefalse Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: info: Command Router : Notfying DB Updatefalse Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: info: Command Router : Notfying DB Updatefalse Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::Close All Modals sent Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseState Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1520261,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cortijo Y Su Combo - Tequila","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:49 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1520261,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cortijo Y Su Combo - Tequila","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:49 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.599+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.599+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.600+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.601+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.601+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.602+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.603+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.604+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio[1236]: info: ------------------------------ 154ms Aug 31 22:35:49 volumiobouix volumio[1236]: info: ------------------------------ 154ms Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 31 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: ControllerMpd::parseTrackInfo Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1520376,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cortijo Y Su Combo - Tequila","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:49 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1520376,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cortijo Y Su Combo - Tequila","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:49 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: ControllerMpd::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::servicePushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CorePlayQueue::getTrack 0 Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1520376,"duration":20,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cortijo Y Su Combo - Tequila","artist":"Jazz Latin by Jazz Radio {Powered by Infomaniak.Net}","album":null,"uri":"http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3","trackType":"mp3"} Aug 31 22:35:49 volumiobouix volumio[1236]: verbose: CURRENT POSITION 0 Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState stateService play Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::syncState currentStatus play Aug 31 22:35:49 volumiobouix volumio[1236]: info: Received an update from plugin. extracting info from payload Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreStateMachine::pushState Aug 31 22:35:49 volumiobouix volumio[1236]: info: CoreCommandRouter::volumioPushState Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.617+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.617+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.620+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.621+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.622+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.622+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.623+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.623+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.623+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.624+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" state=STATUS_PLAYING positionMs=1519790 volume=39 Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.624+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio5-onboarding[1404]: time=2026-08-31T22:35:49.627+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.101:46182 @ 0x25de240" id=http://jazz-wr09.ice.infomaniak.ch/jazz-wr09-128.mp3 title="Cortijo Y Su Combo - Tequila" Aug 31 22:35:49 volumiobouix volumio[1236]: info: ------------------------------ 144ms Aug 31 22:35:49 volumiobouix volumio[1236]: info: ------------------------------ 100ms Aug 31 22:35:49 volumiobouix volumio[1236]: info: ------------------------------ 100ms Aug 31 22:35:52 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:56 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:35:57 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 31 22:35:57 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 31 22:35:59 volumiobouix volumio[1236]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L bouix-ThinkCentre-M710q.local. -m SMB3_11 Aug 31 22:36:00 volumiobouix volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 22:36:02 volumiobouix volumio[1236]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 22:36:02 volumiobouix volumio[1236]: Error: dns service error: unknown Aug 31 22:36:02 volumiobouix volumio[1236]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Aug 31 22:36:02 volumiobouix volumio[1236]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Aug 31 22:36:02 volumiobouix volumio[1236]: errorCode: -65537 Aug 31 22:36:02 volumiobouix volumio[1236]: } Aug 31 22:36:02 volumiobouix volumio[1236]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 22:36:03 volumiobouix sudo[14983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 22:35' Aug 31 22:36:03 volumiobouix sudo[14983]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"