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"