-- Logs begin at Sat 2026-10-03 09:18:40 CEST, end at Sat 2026-10-03 09:21:04 CEST. -- Oct 03 09:20:03 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: Received Get System Version Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: Received Get System Info Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: Discovery: Getting this device information Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetState Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:11 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPlay Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: CoreStateMachine::play index undefined Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: CoreStateMachine::startPlaybackTimer Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: [1791012018131] ControllerWebradio::clearAddPlayTrack Oct 03 09:20:18 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: sendMpdCommand stop took 1 milliseconds Oct 03 09:20:18 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: Oct 03 09:20:18 volumio-pi5 volumio[1027]: ---------------------------- MPD announces system playlist update Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: Ignoring MPD Status Update Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: sendMpdCommand clear took 1 milliseconds Oct 03 09:20:18 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.radiofrance.fr/francemusique-hifi.aac" Oct 03 09:20:18 volumio-pi5 volumio[1027]: error: updateQueue error: null Oct 03 09:20:18 volumio-pi5 volumio[1027]: info: ------------------------------ 1ms Oct 03 09:20:19 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.radiofrance.fr/francemusique-hifi.aac" Oct 03 09:20:19 volumio-pi5 volumio[1027]: info: Oct 03 09:20:19 volumio-pi5 volumio[1027]: ---------------------------- MPD announces system playlist update Oct 03 09:20:19 volumio-pi5 volumio[1027]: info: Ignoring MPD Status Update Oct 03 09:20:19 volumio-pi5 volumio[1027]: info: sendMpdCommand add "http://icecast.radiofrance.fr/francemusique-hifi.aac" took 0 milliseconds Oct 03 09:20:19 volumio-pi5 volumio[1027]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 09:20:19 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand play Oct 03 09:20:19 volumio-pi5 volumio[1027]: info: ------------------------------ 1ms Oct 03 09:20:19 volumio-pi5 volumio[1027]: info: sendMpdCommand play took 0 milliseconds Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: Oct 03 09:20:20 volumio-pi5 volumio[1027]: ---------------------------- MPD announces state update: player Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: ControllerMpd::getState Oct 03 09:20:20 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: Oct 03 09:20:20 volumio-pi5 volumio[1027]: ---------------------------- MPD announces system playlist update Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: Ignoring MPD Status Update Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: Oct 03 09:20:20 volumio-pi5 volumio[1027]: ---------------------------- MPD announces state update: player Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: sendMpdCommand status took 9 milliseconds Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: ControllerMpd::getState Oct 03 09:20:20 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:20:20 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseState Oct 03 09:20:20 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: ------------------------------ 2ms Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: sendMpdCommand status took 1 milliseconds Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 03 09:20:20 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseState Oct 03 09:20:20 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:20:20 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: ControllerMpd::pushState Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreCommandRouter::servicePushState Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:20 volumio-pi5 volumio[1027]: 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":"186 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:20:20 volumio-pi5 volumio[1027]: verbose: CURRENT POSITION 0 Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState stateService play Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: ------------------------------ 13ms Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 09:20:20 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: ControllerMpd::pushState Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreCommandRouter::servicePushState Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:20 volumio-pi5 volumio[1027]: 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":"186 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:20:20 volumio-pi5 volumio[1027]: verbose: CURRENT POSITION 0 Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState stateService play Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState currentStatus play Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: Received an update from plugin. extracting info from payload Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreStateMachine::pushState Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPushState Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetState Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreStateMachine::pushState Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPushState Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetState Oct 03 09:20:20 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:20.089+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:20:20 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:20.091+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:20:20 volumio-pi5 volumio[1027]: info: ------------------------------ 16ms Oct 03 09:20:20 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:20.184+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" id=http://icecast.radiofrance.fr/francemusique-hifi.aac title="France Musique" Oct 03 09:20:20 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:20.380+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" id=http://icecast.radiofrance.fr/francemusique-hifi.aac title="France Musique" Oct 03 09:20:26 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetQueue Oct 03 09:20:26 volumio-pi5 volumio[1027]: info: CoreStateMachine::getQueue Oct 03 09:20:26 volumio-pi5 volumio[1027]: info: CorePlayQueue::getQueue Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: Preload queue cleared Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::ClearQueue Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::stop Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::stPlaybackTimer Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::updateTrackBlock Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrackBlock Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::pushState Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPushState Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetState Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::serviceStop Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::serviceStop Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: [1791012028068] ControllerWebradio::stop Oct 03 09:20:28 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::clearPlayQueue Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::saveQueue Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPushQueue Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::addQueueItems Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::addQueueItems Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: Preload queue cleared Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: Adding Item to queue: http://direct.franceinter.fr/live/franceinter-hifi.aac Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 03 09:20:28 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:28.072+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPushQueue Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::saveQueue Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::updateTrackBlock Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrackBlock Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPlay Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::play index 0 Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::stop Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::play index undefined Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreStateMachine::startPlaybackTimer Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: [1791012028080] ControllerWebradio::clearAddPlayTrack Oct 03 09:20:28 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: Oct 03 09:20:28 volumio-pi5 volumio[1027]: ---------------------------- MPD announces state update: player Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: sendMpdCommand stop took 14 milliseconds Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: ControllerMpd::getState Oct 03 09:20:28 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: sendMpdCommand stop took 3 milliseconds Oct 03 09:20:28 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: Oct 03 09:20:28 volumio-pi5 volumio[1027]: ---------------------------- MPD announces system playlist update Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: Ignoring MPD Status Update Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: sendMpdCommand status took 1 milliseconds Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: sendMpdCommand clear took 0 milliseconds Oct 03 09:20:28 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseState Oct 03 09:20:28 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:20:28 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand load "http://direct.franceinter.fr/live/franceinter-hifi.aac" Oct 03 09:20:28 volumio-pi5 volumio[1027]: error: updateQueue error: null Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: ------------------------------ 1ms Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 09:20:28 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:20:28 volumio-pi5 volumio[1027]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 03 09:20:28 volumio-pi5 volumio[1027]: info: ------------------------------ 2ms Oct 03 09:20:28 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:28.131+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" id=http://icecast.radiofrance.fr/francemusique-hifi.aac title="France Musique" Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand add "http://direct.franceinter.fr/live/franceinter-hifi.aac" Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: Oct 03 09:20:29 volumio-pi5 volumio[1027]: ---------------------------- MPD announces system playlist update Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: Ignoring MPD Status Update Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: sendMpdCommand add "http://direct.franceinter.fr/live/franceinter-hifi.aac" took 1 milliseconds Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand play Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ------------------------------ 1ms Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: sendMpdCommand play took 1 milliseconds Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: Oct 03 09:20:29 volumio-pi5 volumio[1027]: ---------------------------- MPD announces state update: player Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ControllerMpd::getState Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: Oct 03 09:20:29 volumio-pi5 volumio[1027]: ---------------------------- MPD announces state update: player Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: sendMpdCommand status took 1 milliseconds Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ControllerMpd::getState Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseState Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: sendMpdCommand status took 1 milliseconds Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseState Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ControllerMpd::pushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::servicePushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:29 volumio-pi5 volumio[1027]: 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":"franceinter-hifi.aac","artist":null,"album":null,"uri":"http://direct.franceinter.fr/live/franceinter-hifi.aac","trackType":"aac"} Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: CURRENT POSITION 0 Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState stateService play Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState currentStatus stop Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ------------------------------ 3ms Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: Oct 03 09:20:29 volumio-pi5 volumio[1027]: ---------------------------- MPD announces system playlist update Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: Ignoring MPD Status Update Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: Oct 03 09:20:29 volumio-pi5 volumio[1027]: ---------------------------- MPD announces state update: player Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ControllerMpd::getState Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand status Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ControllerMpd::pushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::servicePushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:29 volumio-pi5 volumio[1027]: 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":"franceinter-hifi.aac","artist":null,"album":null,"uri":"http://direct.franceinter.fr/live/franceinter-hifi.aac","trackType":"aac"} Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: CURRENT POSITION 0 Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState stateService play Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState currentStatus play Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: Received an update from plugin. extracting info from payload Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::pushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::pushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetState Oct 03 09:20:29 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:29.853+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:20:29 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:29.854+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ------------------------------ 19ms Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ------------------------------ 18ms Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: sendMpdCommand status took 17 milliseconds Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseState Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: ControllerMpd::parseTrackInfo Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ControllerMpd::pushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::servicePushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:29 volumio-pi5 volumio[1027]: 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":"185 Kbps","isStreaming":false,"title":"franceinter-hifi.aac","artist":"franceinter-hifi.aac","album":null,"uri":"http://direct.franceinter.fr/live/franceinter-hifi.aac","trackType":"aac"} Oct 03 09:20:29 volumio-pi5 volumio[1027]: verbose: CURRENT POSITION 0 Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState stateService play Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::syncState currentStatus play Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: Received an update from plugin. extracting info from payload Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::pushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CorePlayQueue::getTrack 0 Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreStateMachine::pushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioPushState Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output update for this device Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: MRS: Pushing multiroomSync output Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: CoreCommandRouter::volumioGetState Oct 03 09:20:29 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:29.871+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:20:29 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:29.872+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" state=STATUS_PLAYING positionMs=0 volume=100 Oct 03 09:20:29 volumio-pi5 volumio[1027]: info: ------------------------------ 34ms Oct 03 09:20:29 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:29.934+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" id=http://direct.franceinter.fr/live/franceinter-hifi.aac title="France Inter" Oct 03 09:20:30 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:30.032+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" id=http://direct.franceinter.fr/live/franceinter-hifi.aac title="France Inter" Oct 03 09:20:30 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:30.129+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" id=http://direct.franceinter.fr/live/franceinter-hifi.aac title="France Inter" Oct 03 09:20:30 volumio-pi5 volumio5-onboarding[1172]: time=2026-10-03T09:20:30.227+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x1553290" id=http://direct.franceinter.fr/live/franceinter-hifi.aac title="France Inter" Oct 03 09:20:42 volumio-pi5 volumiologrotate[776]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 03 09:20:42 volumio-pi5 volumiologrotate[776]: ls: cannot access 'PI5': No such file or directory Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: MRS: Starting cast device: Google-Nest-Mini-1fdab47e53d10132b2e2832fcb2eabe4 Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: Enabled audio output: Google-Nest-Mini-1fdab47e53d10132b2e2832fcb2eabe4 Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: MRS: Setting this device as Streaming Server Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: Oct 03 09:20:45 volumio-pi5 volumio[1027]: [1791012045943] ---------------------------- MRS: Setting Streaming Server Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: MRS: enable multiroom server output Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: MRS: Set multiroom target PCM to volumioMultiRoom Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Oct 03 09:20:45 volumio-pi5 volumio[1027]: info: MRS: STARTING volumioStreaming Oct 03 09:20:45 volumio-pi5 sudo[1825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 03 09:20:45 volumio-pi5 sudo[1825]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:20:45 volumio-pi5 sudo[1825]: pam_unix(sudo:session): session closed for user root Oct 03 09:20:45 volumio-pi5 sudo[1828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Oct 03 09:20:45 volumio-pi5 sudo[1828]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:20:46 volumio-pi5 systemd[1]: Started VolumioStreamingService. Oct 03 09:20:46 volumio-pi5 sudo[1828]: pam_unix(sudo:session): session closed for user root Oct 03 09:20:46 volumio-pi5 volumio[1027]: info: MRS: volumioStreaming STARTED Oct 03 09:20:46 volumio-pi5 sudo[1831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 03 09:20:46 volumio-pi5 sudo[1831]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:20:46 volumio-pi5 sudo[1831]: pam_unix(sudo:session): session closed for user root Oct 03 09:20:46 volumio-pi5 volumio[1027]: info: MRS: Connected to cast device: Google-Nest-Mini-1fdab47e53d10132b2e2832fcb2eabe4 Oct 03 09:20:46 volumio-pi5 volumio[1027]: info: Refreshing Cached IP Addresses Oct 03 09:20:46 volumio-pi5 sudo[1835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 09:20:46 volumio-pi5 sudo[1835]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:20:46 volumio-pi5 sudo[1835]: pam_unix(sudo:session): session closed for user root Oct 03 09:20:46 volumio-pi5 sudo[1838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 09:20:46 volumio-pi5 sudo[1838]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:20:46 volumio-pi5 sudo[1838]: pam_unix(sudo:session): session closed for user root Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: MRS: Stopping cast device: Google-Nest-Mini-1fdab47e53d10132b2e2832fcb2eabe4 Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: MRS: Starting cast device: Google-Cast-Group-dcf9cf6e38db4b7280cddbe3c00e8bac Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: Enabled audio output: Google-Cast-Group-dcf9cf6e38db4b7280cddbe3c00e8bac Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: MRS: Setting this device as Streaming Server Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: Oct 03 09:21:04 volumio-pi5 volumio[1027]: [1791012064701] ---------------------------- MRS: Setting Streaming Server Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: MRS: enable multiroom server output Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: MRS: Set multiroom target PCM to volumioMultiRoom Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: MRS: STARTING volumioStreaming Oct 03 09:21:04 volumio-pi5 sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream14.ts /tmp/hls/stream15.ts /tmp/hls/stream16.ts /tmp/hls/stream17.ts /tmp/hls/stream18.ts Oct 03 09:21:04 volumio-pi5 sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:21:04 volumio-pi5 sudo[1861]: pam_unix(sudo:session): session closed for user root Oct 03 09:21:04 volumio-pi5 sudo[1864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Oct 03 09:21:04 volumio-pi5 sudo[1864]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:21:04 volumio-pi5 systemd[1]: Stopping VolumioStreamingService... Oct 03 09:21:04 volumio-pi5 systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Oct 03 09:21:04 volumio-pi5 systemd[1]: volumioStreaming.service: Succeeded. Oct 03 09:21:04 volumio-pi5 systemd[1]: Stopped VolumioStreamingService. Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: MRS: Connected to cast device: Google-Cast-Group-dcf9cf6e38db4b7280cddbe3c00e8bac Oct 03 09:21:04 volumio-pi5 systemd[1]: Started VolumioStreamingService. Oct 03 09:21:04 volumio-pi5 sudo[1864]: pam_unix(sudo:session): session closed for user root Oct 03 09:21:04 volumio-pi5 volumio[1027]: info: MRS: volumioStreaming STARTED Oct 03 09:21:04 volumio-pi5 sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 03 09:21:04 volumio-pi5 sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 09:21:04 volumio-pi5 sudo[1867]: pam_unix(sudo:session): session closed for user root Oct 03 09:21:04 volumio-pi5 volumio[1027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 09:21:04 volumio-pi5 volumio[1027]: TypeError: Cannot read property 'on' of undefined Oct 03 09:21:04 volumio-pi5 volumio[1027]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29834 Oct 03 09:21:04 volumio-pi5 volumio[1027]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Oct 03 09:21:04 volumio-pi5 volumio[1027]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Oct 03 09:21:04 volumio-pi5 volumio[1027]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at fn.emit (events.js:327:22) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at Channel.emit (events.js:315:20) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at Client.emit (events.js:327:22) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at PacketStreamWrapper.emit (events.js:315:20) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at TLSSocket. (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at TLSSocket.emit (events.js:315:20) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at emitReadable_ (internal/streams/readable.js:569:12) Oct 03 09:21:04 volumio-pi5 volumio[1027]: at processTicksAndRejections (internal/process/task_queues.js:79:21) Oct 03 09:21:04 volumio-pi5 volumio[1027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 09:21:04 volumio-pi5 sudo[1879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-03 09:20 Oct 03 09:21:04 volumio-pi5 sudo[1879]: 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"