Apr 13 09:48:10 volumio volumio[1349]: info: BOOT COMPLETED Apr 13 09:48:10 volumio volumio[1349]: info: AutoStart - Check #13/60 - VOLUMIO_SYSTEM_STATUS = ready Apr 13 09:48:10 volumio volumio[1349]: info: AutoStart - System ready state CONFIRMED after 13 checks Apr 13 09:48:10 volumio volumio[1349]: info: AutoStart - Applying additional delay of 20000ms before playback Apr 13 09:48:10 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 09:48:10 volumio volumio[1349]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 13 09:48:10 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 13 09:48:10 volumio volumio[1349]: info: Received Get System Version Apr 13 09:48:10 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 09:48:10 volumio volumio[1349]: info: Received Get System Info Apr 13 09:48:10 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 09:48:10 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 09:48:10 volumio volumio[1349]: info: Discovery: Getting this device information Apr 13 09:48:10 volumio volumio[1349]: info: CoreCommandRouter::volumioGetState Apr 13 09:48:10 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:10 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 09:48:45 volumio volumio[1349]: info: [ytmusic] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Apr 13 09:48:45 volumio volumio[1349]: info: [ytmusic] Innertube support service: Start service with Node Apr 13 09:48:45 volumio volumio[1349]: info: AutoStart - startPlayback called Apr 13 09:48:45 volumio volumio[1349]: info: CoreCommandRouter::volumioGetQueue Apr 13 09:48:45 volumio volumio[1349]: info: CoreStateMachine::getQueue Apr 13 09:48:45 volumio volumio[1349]: info: CorePlayQueue::getQueue Apr 13 09:48:45 volumio volumio[1349]: info: AutoStart - Queue has 1 items Apr 13 09:48:45 volumio volumio[1349]: info: AutoStart - Playing from position 0 Apr 13 09:48:45 volumio volumio[1349]: info: CoreCommandRouter::volumioPlay Apr 13 09:48:45 volumio volumio[1349]: info: CoreStateMachine::play index 0 Apr 13 09:48:45 volumio volumio[1349]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 09:48:45 volumio volumio[1349]: info: CoreStateMachine::stop Apr 13 09:48:45 volumio volumio[1349]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 09:48:45 volumio volumio[1349]: info: CoreStateMachine::play index undefined Apr 13 09:48:45 volumio volumio[1349]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 09:48:45 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:45 volumio volumio[1349]: info: CoreStateMachine::startPlaybackTimer Apr 13 09:48:45 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:45 volumio volumio[1349]: info: [1776066525616] ControllerWebradio::clearAddPlayTrack Apr 13 09:48:45 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 09:48:45 volumio volumio[1349]: info: sendMpdCommand stop took 100 milliseconds Apr 13 09:48:45 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand clear Apr 13 09:48:45 volumio volumio[1349]: info: Apr 13 09:48:45 volumio volumio[1349]: ---------------------------- MPD announces system playlist update Apr 13 09:48:45 volumio volumio[1349]: info: Ignoring MPD Status Update Apr 13 09:48:45 volumio volumio[1349]: info: sendMpdCommand clear took 99 milliseconds Apr 13 09:48:45 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand load "https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Apr 13 09:48:45 volumio volumio[1349]: error: updateQueue error: null Apr 13 09:48:45 volumio volumio[1349]: info: ------------------------------ 26ms Apr 13 09:48:47 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand add "https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" Apr 13 09:48:47 volumio volumio[1349]: info: Apr 13 09:48:47 volumio volumio[1349]: ---------------------------- MPD announces system playlist update Apr 13 09:48:47 volumio volumio[1349]: info: Ignoring MPD Status Update Apr 13 09:48:47 volumio volumio[1349]: info: sendMpdCommand add "https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" took 2 milliseconds Apr 13 09:48:47 volumio volumio[1349]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 13 09:48:47 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand play Apr 13 09:48:47 volumio volumio[1349]: info: ------------------------------ 4ms Apr 13 09:48:47 volumio volumio[1349]: info: sendMpdCommand play took 21 milliseconds Apr 13 09:48:48 volumio volumio[1349]: info: Apr 13 09:48:48 volumio volumio[1349]: ---------------------------- MPD announces state update: player Apr 13 09:48:48 volumio volumio[1349]: info: ControllerMpd::getState Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:48:48 volumio volumio[1349]: info: Apr 13 09:48:48 volumio volumio[1349]: ---------------------------- MPD announces state update: player Apr 13 09:48:48 volumio volumio[1349]: info: sendMpdCommand status took 26 milliseconds Apr 13 09:48:48 volumio volumio[1349]: info: ControllerMpd::getState Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::parseState Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:48:48 volumio volumio[1349]: info: sendMpdCommand status took 38 milliseconds Apr 13 09:48:48 volumio volumio[1349]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::parseState Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:48:48 volumio volumio[1349]: info: ControllerMpd::pushState Apr 13 09:48:48 volumio volumio[1349]: info: CoreCommandRouter::servicePushState Apr 13 09:48:48 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:48 volumio volumio[1349]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 13 09:48:48 volumio volumio[1349]: verbose: CURRENT POSITION 0 Apr 13 09:48:48 volumio volumio[1349]: info: CoreStateMachine::syncState stateService play Apr 13 09:48:48 volumio volumio[1349]: info: CoreStateMachine::syncState currentStatus stop Apr 13 09:48:48 volumio volumio[1349]: info: ------------------------------ 131ms Apr 13 09:48:48 volumio volumio[1349]: info: Apr 13 09:48:48 volumio volumio[1349]: ---------------------------- MPD announces system playlist update Apr 13 09:48:48 volumio volumio[1349]: info: Ignoring MPD Status Update Apr 13 09:48:48 volumio volumio[1349]: info: Apr 13 09:48:48 volumio volumio[1349]: ---------------------------- MPD announces state update: player Apr 13 09:48:48 volumio volumio[1349]: info: Apr 13 09:48:48 volumio volumio[1349]: ---------------------------- MPD announces system playlist update Apr 13 09:48:48 volumio volumio[1349]: info: Ignoring MPD Status Update Apr 13 09:48:48 volumio volumio[1349]: info: Apr 13 09:48:48 volumio volumio[1349]: ---------------------------- MPD announces state update: player Apr 13 09:48:48 volumio volumio[1349]: info: ControllerMpd::getState Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:48:48 volumio volumio[1349]: info: sendMpdCommand playlistinfo took 119 milliseconds Apr 13 09:48:48 volumio volumio[1349]: info: ControllerMpd::getState Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:48:48 volumio volumio[1349]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:48:48 volumio volumio[1349]: info: ControllerMpd::pushState Apr 13 09:48:48 volumio volumio[1349]: info: CoreCommandRouter::servicePushState Apr 13 09:48:48 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:48 volumio volumio[1349]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna Lewis - I Love You Always Forever","artist":"ANTENNE BAYERN","album":null,"uri":"https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 13 09:48:48 volumio volumio[1349]: verbose: CURRENT POSITION 0 Apr 13 09:48:48 volumio volumio[1349]: info: CoreStateMachine::syncState stateService play Apr 13 09:48:48 volumio volumio[1349]: info: CoreStateMachine::syncState currentStatus play Apr 13 09:48:48 volumio volumio[1349]: info: Received an update from plugin. extracting info from payload Apr 13 09:48:48 volumio volumio[1349]: info: CoreStateMachine::pushState Apr 13 09:48:48 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 09:48:48 volumio volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 13 09:48:48 volumio volumio[1349]: info: CoreStateMachine::pushState Apr 13 09:48:48 volumio volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 13 09:48:48 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:48.572+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" state=STATUS_PLAYING positionMs=287 volume=15 Apr 13 09:48:48 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:48.572+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" id="https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" title="Donna Lewis - I Love You Always Forever" Apr 13 09:48:48 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:48.575+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" state=STATUS_PLAYING positionMs=287 volume=15 Apr 13 09:48:48 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:48.576+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" id="https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" title="Donna Lewis - I Love You Always Forever" Apr 13 09:48:48 volumio volumio[1349]: info: ------------------------------ 345ms Apr 13 09:48:48 volumio volumio[1349]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 13 09:48:48 volumio volumio[1349]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 13 09:48:48 volumio volumio[1349]: info: camilladsp stopping service pid 1843... Apr 13 09:48:49 volumio volumio[1349]: grep: /proc/1843/cmdline: binary file matches Apr 13 09:48:49 volumio volumio[1349]: grep: /proc/1843/cmdline: binary file matches Apr 13 09:48:49 volumio volumio[1349]: grep: /proc/1843/cmdline: binary file matches Apr 13 09:48:49 volumio volumio[1349]: grep: /proc/1843/cmdline: binary file matches Apr 13 09:48:49 volumio volumio[1349]: info: camilladsp service terminated, instance 1 Apr 13 09:48:49 volumio volumio[1349]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 13 09:48:49 volumio volumio[1349]: info: FusionDsp - Crossfeed selected : jameier Apr 13 09:48:49 volumio volumio[1349]: info: camilladsp service started and running in background, instance 1 Apr 13 09:48:49 volumio volumio[1349]: info: ------------------------------ 975ms Apr 13 09:48:49 volumio volumio[1349]: info: sendMpdCommand status took 952 milliseconds Apr 13 09:48:49 volumio volumio[1349]: info: ------------------------------ 975ms Apr 13 09:48:49 volumio volumio[1349]: info: sendMpdCommand status took 952 milliseconds Apr 13 09:48:49 volumio volumio[1349]: verbose: ControllerMpd::parseState Apr 13 09:48:49 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:48:49 volumio volumio[1349]: verbose: ControllerMpd::parseState Apr 13 09:48:49 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:48:49 volumio volumio[1349]: info: camilladsp respawn in 100 ms (attempt 1/10) Apr 13 09:48:49 volumio volumio[1349]: info: sendMpdCommand playlistinfo took 208 milliseconds Apr 13 09:48:49 volumio volumio[1349]: info: sendMpdCommand playlistinfo took 208 milliseconds Apr 13 09:48:49 volumio volumio[1349]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:48:49 volumio volumio[1349]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:48:49 volumio volumio[1349]: info: ControllerMpd::pushState Apr 13 09:48:49 volumio volumio[1349]: info: CoreCommandRouter::servicePushState Apr 13 09:48:49 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:49 volumio volumio[1349]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Donna Lewis - I Love You Always Forever","artist":"ANTENNE BAYERN","album":null,"uri":"https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 13 09:48:49 volumio volumio[1349]: verbose: CURRENT POSITION 0 Apr 13 09:48:49 volumio volumio[1349]: info: CoreStateMachine::syncState stateService play Apr 13 09:48:49 volumio volumio[1349]: info: CoreStateMachine::syncState currentStatus play Apr 13 09:48:49 volumio volumio[1349]: info: Received an update from plugin. extracting info from payload Apr 13 09:48:49 volumio volumio[1349]: info: CoreStateMachine::pushState Apr 13 09:48:49 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 09:48:49 volumio volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 13 09:48:49 volumio volumio[1349]: info: CoreStateMachine::pushState Apr 13 09:48:49 volumio volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 13 09:48:49 volumio volumio[1349]: info: ControllerMpd::pushState Apr 13 09:48:49 volumio volumio[1349]: info: CoreCommandRouter::servicePushState Apr 13 09:48:49 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:49 volumio volumio[1349]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1227,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Donna Lewis - I Love You Always Forever","artist":"ANTENNE BAYERN","album":null,"uri":"https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 13 09:48:49 volumio volumio[1349]: verbose: CURRENT POSITION 0 Apr 13 09:48:49 volumio volumio[1349]: info: CoreStateMachine::syncState stateService play Apr 13 09:48:49 volumio volumio[1349]: info: CoreStateMachine::syncState currentStatus play Apr 13 09:48:49 volumio volumio[1349]: info: Received an update from plugin. extracting info from payload Apr 13 09:48:49 volumio volumio[1349]: info: CoreStateMachine::pushState Apr 13 09:48:49 volumio volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 13 09:48:49 volumio volumio[1349]: info: CoreStateMachine::pushState Apr 13 09:48:49 volumio volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 13 09:48:49 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:49.826+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" state=STATUS_PLAYING positionMs=1260 volume=15 Apr 13 09:48:49 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:49.826+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" id="https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" title="Donna Lewis - I Love You Always Forever" Apr 13 09:48:49 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:49.827+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" state=STATUS_PLAYING positionMs=1260 volume=15 Apr 13 09:48:49 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:49.827+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" id="https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" title="Donna Lewis - I Love You Always Forever" Apr 13 09:48:49 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:49.865+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" state=STATUS_PLAYING positionMs=1260 volume=15 Apr 13 09:48:49 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:49.866+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" id="https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" title="Donna Lewis - I Love You Always Forever" Apr 13 09:48:49 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:49.875+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" state=STATUS_PLAYING positionMs=1260 volume=15 Apr 13 09:48:49 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:49.875+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" id="https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" title="Donna Lewis - I Love You Always Forever" Apr 13 09:48:49 volumio volumio[1349]: info: ------------------------------ 1570ms Apr 13 09:48:50 volumio volumio[1349]: info: ------------------------------ 1591ms Apr 13 09:48:50 volumio volumio[1349]: info: CoreCommandRouter::volumioGetState Apr 13 09:48:50 volumio volumio[1349]: info: CoreCommandRouter::volumioGetState Apr 13 09:48:50 volumio volumio[1349]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 13 09:48:53 volumio volumio[1349]: info: CoreCommandRouter::volumioStop Apr 13 09:48:53 volumio volumio[1349]: info: CoreStateMachine::stop Apr 13 09:48:53 volumio volumio[1349]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 09:48:53 volumio volumio[1349]: info: CoreStateMachine::stPlaybackTimer Apr 13 09:48:53 volumio volumio[1349]: info: CoreStateMachine::updateTrackBlock Apr 13 09:48:53 volumio volumio[1349]: info: CorePlayQueue::getTrackBlock Apr 13 09:48:53 volumio volumio[1349]: info: CoreStateMachine::pushState Apr 13 09:48:53 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:53 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 09:48:53 volumio volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 13 09:48:53 volumio volumio[1349]: info: CoreStateMachine::serviceStop Apr 13 09:48:53 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:53 volumio volumio[1349]: info: CoreCommandRouter::serviceStop Apr 13 09:48:53 volumio volumio[1349]: info: [1776066533518] ControllerWebradio::stop Apr 13 09:48:53 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 09:48:53 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:53.570+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" state=STATUS_STOPPED positionMs=0 volume=15 Apr 13 09:48:53 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:53.570+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" id="https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" title="Antenne Bayern" Apr 13 09:48:53 volumio volumio[1349]: info: Apr 13 09:48:53 volumio volumio[1349]: ---------------------------- MPD announces state update: player Apr 13 09:48:53 volumio volumio[1349]: info: sendMpdCommand stop took 208 milliseconds Apr 13 09:48:53 volumio volumio[1349]: info: ControllerMpd::getState Apr 13 09:48:53 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand status Apr 13 09:48:53 volumio volumio[1349]: info: sendMpdCommand status took 1 milliseconds Apr 13 09:48:53 volumio volumio[1349]: verbose: ControllerMpd::parseState Apr 13 09:48:53 volumio volumio[1349]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 09:48:53 volumio volumio[1349]: info: sendMpdCommand playlistinfo took 149 milliseconds Apr 13 09:48:53 volumio volumio[1349]: verbose: ControllerMpd::parseTrackInfo Apr 13 09:48:53 volumio volumio[1349]: info: ControllerMpd::pushState Apr 13 09:48:53 volumio volumio[1349]: info: CoreCommandRouter::servicePushState Apr 13 09:48:53 volumio volumio[1349]: info: CoreStateMachine::pushState Apr 13 09:48:53 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:53 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 09:48:53 volumio volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 13 09:48:53 volumio volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 13 09:48:53 volumio volumio[1349]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 13 09:48:53 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:53.948+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" state=STATUS_STOPPED positionMs=417 volume=15 Apr 13 09:48:53 volumio volumio5-onboarding[1716]: time=2026-04-13T09:48:53.948+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.48:55175 @ 0x2bd1b00" id="https://stream.antenne.de/antenne/stream/mp3?aw_0_1st.playerid=tunein.com" title="Antenne Bayern" Apr 13 09:48:53 volumio volumio[1349]: info: ------------------------------ 253ms Apr 13 09:48:53 volumio volumio[1349]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 13 09:48:54 volumio volumio[1349]: info: camilladsp respawn in 200 ms (attempt 2/10) Apr 13 09:48:55 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 09:48:55 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 09:48:59 volumio volumio[1349]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 13 09:49:03 volumio volumio[1349]: info: [ytmusic] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":46381}} Apr 13 09:49:03 volumio volumio[1349]: info: [ytmusic] Innertube support service running at http://127.0.0.1:46381 Apr 13 09:49:22 volumio volumio[1349]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out Apr 13 09:49:22 volumio volumio[1349]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Apr 13 09:49:22 volumio volumio[1349]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 13 09:49:22 volumio volumio[1349]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Apr 13 09:49:22 volumio volumio[1349]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Apr 13 09:49:22 volumio volumio[1349]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Apr 13 09:49:22 volumio volumio[1349]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Apr 13 09:49:22 volumio volumio[1349]: error: [ytmusic] Error getting i18n options: VM operation timed out Error: VM operation timed out Apr 13 09:49:22 volumio volumio[1349]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Apr 13 09:49:22 volumio volumio[1349]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 13 09:49:22 volumio volumio[1349]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Apr 13 09:49:22 volumio volumio[1349]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Apr 13 09:49:22 volumio volumio[1349]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Apr 13 09:49:22 volumio volumio[1349]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Apr 13 09:49:22 volumio volumio[1349]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out Apr 13 09:49:22 volumio volumio[1349]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Apr 13 09:49:22 volumio volumio[1349]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 13 09:49:22 volumio volumio[1349]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Apr 13 09:49:22 volumio volumio[1349]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Apr 13 09:49:22 volumio volumio[1349]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Apr 13 09:49:22 volumio volumio[1349]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Apr 13 09:49:22 volumio volumio[1349]: warn: [ytmusic] Failed to get account config: VM operation timed out Error: VM operation timed out Apr 13 09:49:22 volumio volumio[1349]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Apr 13 09:49:22 volumio volumio[1349]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 13 09:49:22 volumio volumio[1349]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Apr 13 09:49:22 volumio volumio[1349]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Apr 13 09:49:22 volumio volumio[1349]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Apr 13 09:49:22 volumio volumio[1349]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Apr 13 09:49:39 volumio volumio[1349]: info: CALLMETHOD: music_service ytmusic configSaveAccount [object Object] Apr 13 09:49:39 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveAccount Apr 13 09:49:45 volumio volumio[1349]: info: CALLMETHOD: music_service ytmusic configSaveI18n [object Object] Apr 13 09:49:45 volumio volumio[1349]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveI18n Apr 13 09:49:45 volumio volumio[1349]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 09:49:45 volumio volumio[1349]: Error: VM operation timed out Apr 13 09:49:45 volumio volumio[1349]: at InnertubeWrapper.generatePoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:145:15) Apr 13 09:49:45 volumio volumio[1349]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 13 09:49:45 volumio volumio[1349]: at async #generateSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:169:35) Apr 13 09:49:45 volumio volumio[1349]: at async #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:113:25) Apr 13 09:49:45 volumio volumio[1349]: at async #init (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:97:9) Apr 13 09:49:45 volumio volumio[1349]: at async InnertubeWrapper.create (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:57:9) Apr 13 09:49:45 volumio volumio[1349]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 09:49:48 volumio sudo[2764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-13 09:48' Apr 13 09:49:48 volumio sudo[2764]: 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"