-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2026-10-03 09:17:27 CEST. -- Oct 03 09:16:30 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioGetQueue Oct 03 09:16:30 volumio-pi5 volumio[1014]: info: CoreStateMachine::getQueue Oct 03 09:16:30 volumio-pi5 volumio[1014]: info: CorePlayQueue::getQueue Oct 03 09:16:34 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 03 09:16:34 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 09:16:34 volumio-pi5 volumio[1014]: info: Preload queue cleared Oct 03 09:16:40 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 03 09:16:40 volumio-pi5 volumio[1014]: info: Preload queue cleared Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: Preload queue cleared Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::ClearQueue Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::stop Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CorePlayQueue::clearPlayQueue Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CorePlayQueue::saveQueue Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioPushQueue Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::addQueueItems Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CorePlayQueue::addQueueItems Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: Preload queue cleared Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: Adding Item to queue: http://icecast.radiofrance.fr/francemusique-hifi.aac Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioPushQueue Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CorePlayQueue::saveQueue Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::updateTrackBlock Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrackBlock Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioPlay Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::play index 0 Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::stop Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::play index undefined Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreStateMachine::startPlaybackTimer Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: [1791011802915] ControllerWebradio::clearAddPlayTrack Oct 03 09:16:42 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: sendMpdCommand stop took 4 milliseconds Oct 03 09:16:42 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: Oct 03 09:16:42 volumio-pi5 volumio[1014]: ---------------------------- MPD announces system playlist update Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: Ignoring MPD Status Update Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: sendMpdCommand clear took 1 milliseconds Oct 03 09:16:42 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.radiofrance.fr/francemusique-hifi.aac" Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: Oct 03 09:16:42 volumio-pi5 volumio[1014]: ---------------------------- MPD announces system playlist update Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: Ignoring MPD Status Update Oct 03 09:16:42 volumio-pi5 volumio[1014]: error: updateQueue error: null Oct 03 09:16:42 volumio-pi5 volumio[1014]: info: ------------------------------ 1ms Oct 03 09:16:43 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.radiofrance.fr/francemusique-hifi.aac" Oct 03 09:16:43 volumio-pi5 volumio[1014]: error: updateQueue error: null Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: Oct 03 09:16:43 volumio-pi5 volumio[1014]: ---------------------------- MPD announces system playlist update Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: Ignoring MPD Status Update Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: ------------------------------ 705ms Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: sendMpdCommand add "http://icecast.radiofrance.fr/francemusique-hifi.aac" took 0 milliseconds Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 09:16:43 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand play Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: Oct 03 09:16:43 volumio-pi5 volumio[1014]: ---------------------------- MPD announces system playlist update Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: Ignoring MPD Status Update Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: ------------------------------ 2ms Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: sendMpdCommand play took 1 milliseconds Oct 03 09:16:43 volumio-pi5 volumio[1014]: info: ------------------------------ 1ms Oct 03 09:16:43 volumio-pi5 volumiologrotate[777]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 03 09:16:43 volumio-pi5 volumiologrotate[777]: ls: cannot access 'PI5': No such file or directory Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Oct 03 09:16:44 volumio-pi5 volumio[1014]: ---------------------------- MPD announces state update: player Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ControllerMpd::getState Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Oct 03 09:16:44 volumio-pi5 volumio[1014]: ---------------------------- MPD announces state update: player Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ControllerMpd::getState Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Oct 03 09:16:44 volumio-pi5 volumio[1014]: ---------------------------- MPD announces system playlist update Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Ignoring MPD Status Update Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Oct 03 09:16:44 volumio-pi5 volumio[1014]: ---------------------------- MPD announces state update: player Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: sendMpdCommand status took 12 milliseconds Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ControllerMpd::getState Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: sendMpdCommand status took 12 milliseconds Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::parseState Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::parseState Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Oct 03 09:16:44 volumio-pi5 volumio[1014]: ---------------------------- MPD announces system playlist update Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Ignoring MPD Status Update Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Oct 03 09:16:44 volumio-pi5 volumio[1014]: ---------------------------- MPD announces state update: player Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ControllerMpd::getState Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ------------------------------ 2ms Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: sendMpdCommand status took 2 milliseconds Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ------------------------------ 1ms Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: sendMpdCommand status took 1 milliseconds Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::parseState Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::parseState Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ControllerMpd::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::servicePushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusique-hifi.aac","artist":"francemusique-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusique-hifi.aac","trackType":"aac"} Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: CURRENT POSITION 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::syncState currentStatus stop Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ControllerMpd::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::servicePushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"francemusique-hifi.aac","artist":"francemusique-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusique-hifi.aac","trackType":"aac"} Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: CURRENT POSITION 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ------------------------------ 22ms Oct 03 09:16:44 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:44.640+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:16:44 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:44.641+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ------------------------------ 27ms Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ControllerMpd::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::servicePushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"francemusique-hifi.aac","artist":"francemusique-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusique-hifi.aac","trackType":"aac"} Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: CURRENT POSITION 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ControllerMpd::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::servicePushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"francemusique-hifi.aac","artist":"francemusique-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusique-hifi.aac","trackType":"aac"} Oct 03 09:16:44 volumio-pi5 volumio[1014]: verbose: CURRENT POSITION 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreStateMachine::pushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: MRS: Pushing multiroomSync output Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 03 09:16:44 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:44.654+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:16:44 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:44.654+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:16:44 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:44.655+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:16:44 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:44.655+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ------------------------------ 32ms Oct 03 09:16:44 volumio-pi5 volumio[1014]: info: ------------------------------ 31ms Oct 03 09:16:44 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:44.703+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" id=http://icecast.radiofrance.fr/francemusique-hifi.aac title="France Musique" Oct 03 09:16:44 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:44.801+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" id=http://icecast.radiofrance.fr/francemusique-hifi.aac title="France Musique" Oct 03 09:16:44 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:44.947+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" id=http://icecast.radiofrance.fr/francemusique-hifi.aac title="France Musique" Oct 03 09:16:45 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:45.045+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" id=http://icecast.radiofrance.fr/francemusique-hifi.aac title="France Musique" Oct 03 09:16:45 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:45.142+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" id=http://icecast.radiofrance.fr/francemusique-hifi.aac title="France Musique" Oct 03 09:16:45 volumio-pi5 volumio5-onboarding[1179]: time=2026-10-03T09:16:45.337+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f54fc0" id=http://icecast.radiofrance.fr/francemusique-hifi.aac title="France Musique" Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: MRS: Starting cast device: Google-Nest-Mini-1fdab47e53d10132b2e2832fcb2eabe4 Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: Enabled audio output: Google-Nest-Mini-1fdab47e53d10132b2e2832fcb2eabe4 Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: MRS: Setting this device as Streaming Server Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: Oct 03 09:16:58 volumio-pi5 volumio[1014]: [1791011818598] ---------------------------- MRS: Setting Streaming Server Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: MRS: enable multiroom server output Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: MRS: Set multiroom target PCM to volumioMultiRoom Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: MRS: STARTING volumioStreaming Oct 03 09:16:58 volumio-pi5 sudo[2660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 03 09:16:58 volumio-pi5 sudo[2660]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:16:58 volumio-pi5 sudo[2660]: pam_unix(sudo:session): session closed for user root Oct 03 09:16:58 volumio-pi5 sudo[2663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Oct 03 09:16:58 volumio-pi5 sudo[2663]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:16:58 volumio-pi5 systemd[1]: Started VolumioStreamingService. Oct 03 09:16:58 volumio-pi5 sudo[2663]: pam_unix(sudo:session): session closed for user root Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: MRS: volumioStreaming STARTED Oct 03 09:16:58 volumio-pi5 sudo[2666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 03 09:16:58 volumio-pi5 sudo[2666]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:16:58 volumio-pi5 sudo[2666]: pam_unix(sudo:session): session closed for user root Oct 03 09:16:58 volumio-pi5 volumio[1014]: info: MRS: Connected to cast device: Google-Nest-Mini-1fdab47e53d10132b2e2832fcb2eabe4 Oct 03 09:16:59 volumio-pi5 volumio[1014]: info: Refreshing Cached IP Addresses Oct 03 09:16:59 volumio-pi5 sudo[2674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 09:16:59 volumio-pi5 sudo[2674]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:16:59 volumio-pi5 sudo[2674]: pam_unix(sudo:session): session closed for user root Oct 03 09:16:59 volumio-pi5 sudo[2677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 09:16:59 volumio-pi5 sudo[2677]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:16:59 volumio-pi5 sudo[2677]: pam_unix(sudo:session): session closed for user root Oct 03 09:16:59 volumio-pi5 volumio[1014]: info: MRS: Cast player state: IDLE Oct 03 09:16:59 volumio-pi5 volumio[1014]: info: MRS: cast media loaded Oct 03 09:17:02 volumio-pi5 volumio[1014]: info: MRS: Cast player state: IDLE Oct 03 09:17:22 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Oct 03 09:17:22 volumio-pi5 volumio[1014]: info: MRS: Setting this device as single Oct 03 09:17:22 volumio-pi5 volumio[1014]: info: Oct 03 09:17:22 volumio-pi5 volumio[1014]: [1791011842364] ---------------------------- Setting Multiroom Single Oct 03 09:17:22 volumio-pi5 volumio[1014]: info: MRS: disable multiroom output Oct 03 09:17:22 volumio-pi5 volumio[1014]: info: MRS: Set multiroom target PCM to volumioDiscard Oct 03 09:17:22 volumio-pi5 volumio[1014]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Oct 03 09:17:23 volumio-pi5 volumio[1014]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 03 09:17:23 volumio-pi5 volumio[1014]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Oct 03 09:17:23 volumio-pi5 volumio[1014]: info: MRS: STOPPING volumioStreaming Oct 03 09:17:23 volumio-pi5 sudo[2711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 03 09:17:23 volumio-pi5 sudo[2711]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:17:23 volumio-pi5 volumio[1014]: info: MRS: Stopping cast device: Google-Nest-Mini-1fdab47e53d10132b2e2832fcb2eabe4 Oct 03 09:17:23 volumio-pi5 systemd[1]: Stopping VolumioStreamingService... Oct 03 09:17:23 volumio-pi5 sudo[2714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts Oct 03 09:17:23 volumio-pi5 sudo[2714]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:17:23 volumio-pi5 systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Oct 03 09:17:23 volumio-pi5 sudo[2714]: pam_unix(sudo:session): session closed for user root Oct 03 09:17:23 volumio-pi5 volumio[1014]: info: MRS: Removed streaming files Oct 03 09:17:23 volumio-pi5 systemd[1]: volumioStreaming.service: Succeeded. Oct 03 09:17:23 volumio-pi5 systemd[1]: Stopped VolumioStreamingService. Oct 03 09:17:23 volumio-pi5 sudo[2711]: pam_unix(sudo:session): session closed for user root Oct 03 09:17:23 volumio-pi5 volumio[1014]: info: MRS: volumioStreaming STOPPED Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: MRS: Starting cast device: Google-Cast-Group-dcf9cf6e38db4b7280cddbe3c00e8bac Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: Enabled audio output: Google-Cast-Group-dcf9cf6e38db4b7280cddbe3c00e8bac Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: MRS: Setting this device as Streaming Server Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: Oct 03 09:17:27 volumio-pi5 volumio[1014]: [1791011847080] ---------------------------- MRS: Setting Streaming Server Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: MRS: enable multiroom server output Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: MRS: Set multiroom target PCM to volumioMultiRoom Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: MRS: STARTING volumioStreaming Oct 03 09:17:27 volumio-pi5 sudo[2733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 03 09:17:27 volumio-pi5 sudo[2733]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:17:27 volumio-pi5 sudo[2733]: pam_unix(sudo:session): session closed for user root Oct 03 09:17:27 volumio-pi5 sudo[2736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Oct 03 09:17:27 volumio-pi5 sudo[2736]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:17:27 volumio-pi5 systemd[1]: Started VolumioStreamingService. Oct 03 09:17:27 volumio-pi5 sudo[2736]: pam_unix(sudo:session): session closed for user root Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: MRS: volumioStreaming STARTED Oct 03 09:17:27 volumio-pi5 sudo[2739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 03 09:17:27 volumio-pi5 sudo[2739]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:17:27 volumio-pi5 sudo[2739]: pam_unix(sudo:session): session closed for user root Oct 03 09:17:27 volumio-pi5 volumio[1014]: info: MRS: Connected to cast device: Google-Cast-Group-dcf9cf6e38db4b7280cddbe3c00e8bac Oct 03 09:17:27 volumio-pi5 volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 09:17:27 volumio-pi5 volumio[1014]: TypeError: Cannot read property 'on' of undefined Oct 03 09:17:27 volumio-pi5 volumio[1014]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29834 Oct 03 09:17:27 volumio-pi5 volumio[1014]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Oct 03 09:17:27 volumio-pi5 volumio[1014]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Oct 03 09:17:27 volumio-pi5 volumio[1014]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at fn.emit (events.js:327:22) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at Channel.emit (events.js:315:20) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at Client.emit (events.js:327:22) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at PacketStreamWrapper.emit (events.js:315:20) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at TLSSocket. (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at TLSSocket.emit (events.js:315:20) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at emitReadable_ (internal/streams/readable.js:569:12) Oct 03 09:17:27 volumio-pi5 volumio[1014]: at processTicksAndRejections (internal/process/task_queues.js:79:21) Oct 03 09:17:27 volumio-pi5 volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 09:17:27 volumio-pi5 sudo[2752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-03 09:16 Oct 03 09:17:27 volumio-pi5 sudo[2752]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"