Feb 18 21:45:11 volumio volumio[1368]: info: CALLMETHOD: audio_interface fusiondsp addeq
Feb 18 21:45:11 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , addeq
Feb 18 21:45:11 volumio volumio[1368]: info: FusionDsp - nbre eq 2
Feb 18 21:45:11 volumio volumio[1368]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 18 21:45:11 volumio volumio[1368]: info: FusionDsp - Nulleq applied
Feb 18 21:45:11 volumio volumio[1368]: error: FusionDsp - Error in eqv! Cannot split values!
Feb 18 21:45:11 volumio volumio[1368]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"}
Feb 18 21:45:11 volumio volumio[1368]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 18 21:45:11 volumio volumio[1368]: info: CoreCommandRouter::Close All Modals sent
Feb 18 21:45:22 volumio volumio[1368]: info: VolumeController::SetAlsaVolume100
Feb 18 21:45:22 volumio volumio[1368]: info: CoreStateMachine::pushState
Feb 18 21:45:22 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:45:22 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 18 21:45:22 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState
Feb 18 21:45:22 volumio volumio5-onboarding[1860]: time=2026-02-18T21:45:22.867+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" state=STATUS_STOPPED positionMs=0 volume=100
Feb 18 21:45:22 volumio volumio5-onboarding[1860]: time=2026-02-18T21:45:22.868+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title="Absolut Radio"
Feb 18 21:45:22 volumio volumio[1368]: info: FusionDsp - Volumio is not playing
Feb 18 21:45:22 volumio volumio[1368]: info: FusionDsp - Clipped samples monitor stopped
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Feb 18 21:45:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Feb 18 21:45:34 volumio volumio[1368]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object]
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Feb 18 21:45:34 volumio volumio[1368]: info: Preparing to save Alsa Options, stopping services first
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState
Feb 18 21:45:34 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::volumioStop
Feb 18 21:45:34 volumio volumio[1368]: info: CoreStateMachine::stop
Feb 18 21:45:34 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 18 21:45:34 volumio volumio[1368]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"FiiO BTR7"}}
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 18 21:45:34 volumio volumio[1368]: info: Setting mixer PCM for card FiiO BTR7
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Feb 18 21:45:34 volumio volumio[1368]: info: Updating Volume Controller Parameters: Device: 5 Name: FiiO BTR7 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Feb 18 21:45:34 volumio volumio[1368]: info: Disabling external Volume Control
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 18 21:45:34 volumio volumio[1368]: info: Preparing to generate the ALSA configuration file
Feb 18 21:45:34 volumio volumio[1368]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Feb 18 21:45:34 volumio volumio[1368]: info: Reading ALSA contributions from plugins.
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Feb 18 21:45:34 volumio volumio[1368]: info: VolumeController:: Volume=100 Mute =false
Feb 18 21:45:34 volumio volumio[1368]: info: CoreStateMachine::pushState
Feb 18 21:45:34 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState
Feb 18 21:45:34 volumio volumio5-onboarding[1860]: time=2026-02-18T21:45:34.193+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" state=STATUS_STOPPED positionMs=0 volume=100
Feb 18 21:45:34 volumio volumio5-onboarding[1860]: time=2026-02-18T21:45:34.194+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title="Absolut Radio"
Feb 18 21:45:34 volumio volumio[1368]: info: FusionDsp - Volumio is not playing
Feb 18 21:45:34 volumio volumio[1368]: info: FusionDsp - Clipped samples monitor stopped
Feb 18 21:45:34 volumio volumio[1368]: info: Asound.conf file unchanged, so no further update is needed
Feb 18 21:45:34 volumio volumio[1368]: info: Output device has changed, restarting MPD
Feb 18 21:45:34 volumio volumio[1368]: info: Output device has changed, restarting Shairport Sync
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 21:45:34 volumio sudo[2820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 18 21:45:34 volumio sudo[2820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 21:45:34 volumio sudo[2822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 18 21:45:34 volumio sudo[2822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 21:45:34 volumio sudo[2820]: pam_unix(sudo:session): session closed for user root
Feb 18 21:45:34 volumio volumio[1368]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 18 21:45:34 volumio volumio[1368]: info: MPD Permissions set
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Feb 18 21:45:34 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 18 21:45:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 18 21:45:34 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 18 21:45:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 18 21:45:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 18 21:45:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 18 21:45:34 volumio volumio[1368]: info: Starting Shairport Sync
Feb 18 21:45:34 volumio sudo[2840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 18 21:45:34 volumio sudo[2840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 21:45:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 18 21:45:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 18 21:45:34 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 18 21:45:34 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 18 21:45:34 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 18 21:45:34 volumio systemd[1]: shairport-sync.service: Consumed 1.796s CPU time.
Feb 18 21:45:34 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 18 21:45:34 volumio sudo[2840]: pam_unix(sudo:session): session closed for user root
Feb 18 21:45:34 volumio volumio[1368]: info: Shairport-Sync Started
Feb 18 21:45:34 volumio sudo[2842]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 18 21:45:34 volumio sudo[2842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 18 21:45:34 volumio sudo[2842]: pam_unix(sudo:session): session closed for user root
Feb 18 21:45:34 volumio mpd[2859]: 2026-02-18T21:45:34 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 18 21:45:34 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 18 21:45:34 volumio sudo[2822]: pam_unix(sudo:session): session closed for user root
Feb 18 21:45:34 volumio volumio[1368]: error: updateQueue error: null
Feb 18 21:45:38 volumio volumio[1368]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Feb 18 21:45:38 volumio volumio[1368]: info: CoreStateMachine::getcurrentVolume
Feb 18 21:45:38 volumio volumio[1368]: info: CoreCommandRouter::volumioRetrievevolume
Feb 18 21:45:38 volumio volumio[1368]: info: VolumeController:: Volume=100 Mute =false
Feb 18 21:45:38 volumio volumio[1368]: info: CoreStateMachine::pushState
Feb 18 21:45:38 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:45:38 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState
Feb 18 21:45:38 volumio volumio[1368]: info: CoreStateMachine::updateTrackBlock
Feb 18 21:45:38 volumio volumio[1368]: info: CorePlayQueue::getTrackBlock
Feb 18 21:45:38 volumio volumio[1368]: info: CoreCommandRouter::volumioRetrievevolume
Feb 18 21:45:38 volumio volumio5-onboarding[1860]: time=2026-02-18T21:45:38.178+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" state=STATUS_STOPPED positionMs=0 volume=100
Feb 18 21:45:38 volumio volumio5-onboarding[1860]: time=2026-02-18T21:45:38.178+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title="Absolut Radio"
Feb 18 21:45:38 volumio volumio[1368]: info: FusionDsp - Volumio is not playing
Feb 18 21:45:38 volumio volumio[1368]: info: FusionDsp - Clipped samples monitor stopped
Feb 18 21:45:38 volumio volumio[1368]: info: VolumeController:: Volume=100 Mute =false
Feb 18 21:45:38 volumio volumio[1368]: info: CoreStateMachine::pushState
Feb 18 21:45:38 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:45:38 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState
Feb 18 21:45:38 volumio volumio5-onboarding[1860]: time=2026-02-18T21:45:38.190+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" state=STATUS_STOPPED positionMs=0 volume=100
Feb 18 21:45:38 volumio volumio5-onboarding[1860]: time=2026-02-18T21:45:38.190+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title="Absolut Radio"
Feb 18 21:45:38 volumio volumio[1368]: info: FusionDsp - Volumio is not playing
Feb 18 21:45:38 volumio volumio[1368]: info: FusionDsp - Clipped samples monitor stopped
Feb 18 21:46:00 volumio volumio[1368]: info: CoreCommandRouter::volumioPlay
Feb 18 21:46:00 volumio volumio[1368]: info: CoreStateMachine::play index 0
Feb 18 21:46:00 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 18 21:46:00 volumio volumio[1368]: info: CoreStateMachine::stop
Feb 18 21:46:00 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 18 21:46:00 volumio volumio[1368]: info: CoreStateMachine::play index undefined
Feb 18 21:46:00 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 18 21:46:00 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:46:00 volumio volumio[1368]: info: CoreStateMachine::startPlaybackTimer
Feb 18 21:46:00 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:46:00 volumio volumio[1368]: info: [1771447560267] ControllerWebradio::clearAddPlayTrack
Feb 18 21:46:00 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand stop
Feb 18 21:46:00 volumio volumio[1368]: info: sendMpdCommand stop took 0 milliseconds
Feb 18 21:46:00 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand clear
Feb 18 21:46:00 volumio volumio[1368]: info:
Feb 18 21:46:00 volumio volumio[1368]: ---------------------------- MPD announces system playlist update
Feb 18 21:46:00 volumio volumio[1368]: info: Ignoring MPD Status Update
Feb 18 21:46:00 volumio volumio[1368]: info: sendMpdCommand clear took 1 milliseconds
Feb 18 21:46:00 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand load "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3"
Feb 18 21:46:00 volumio volumio[1368]: error: updateQueue error: null
Feb 18 21:46:00 volumio volumio[1368]: info: ------------------------------ 1ms
Feb 18 21:46:01 volumio volumio[1368]: info: CoreCommandRouter::volumioRemoveQueueItem
Feb 18 21:46:01 volumio volumio[1368]: info: CoreStateMachine::removeQueueItem
Feb 18 21:46:01 volumio volumio[1368]: info: CoreStateMachine::stop
Feb 18 21:46:01 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 18 21:46:01 volumio volumio[1368]: info: CorePlayQueue::removeQueueItem 0
Feb 18 21:46:01 volumio volumio[1368]: info: CorePlayQueue::saveQueue
Feb 18 21:46:01 volumio volumio[1368]: info: CoreCommandRouter::volumioPushQueue
Feb 18 21:46:02 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3"
Feb 18 21:46:02 volumio volumio[1368]: info:
Feb 18 21:46:02 volumio volumio[1368]: ---------------------------- MPD announces system playlist update
Feb 18 21:46:02 volumio volumio[1368]: info: Ignoring MPD Status Update
Feb 18 21:46:02 volumio volumio[1368]: info: sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" took 1 milliseconds
Feb 18 21:46:02 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 18 21:46:02 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand play
Feb 18 21:46:02 volumio volumio[1368]: info: ------------------------------ 1ms
Feb 18 21:46:02 volumio volumio[1368]: info: sendMpdCommand play took 1 milliseconds
Feb 18 21:46:03 volumio volumio[1368]: info:
Feb 18 21:46:03 volumio volumio[1368]: ---------------------------- MPD announces state update: player
Feb 18 21:46:03 volumio volumio[1368]: info: ControllerMpd::getState
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand status
Feb 18 21:46:03 volumio volumio[1368]: info:
Feb 18 21:46:03 volumio volumio[1368]: ---------------------------- MPD announces state update: player
Feb 18 21:46:03 volumio volumio[1368]: info: sendMpdCommand status took 8 milliseconds
Feb 18 21:46:03 volumio volumio[1368]: info: ControllerMpd::getState
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand status
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::parseState
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 18 21:46:03 volumio volumio[1368]: info: sendMpdCommand status took 1 milliseconds
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::parseState
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 18 21:46:03 volumio volumio[1368]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 18 21:46:03 volumio volumio[1368]: info: sendMpdCommand playlistinfo took 0 milliseconds
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::parseTrackInfo
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::parseTrackInfo
Feb 18 21:46:03 volumio volumio[1368]: info: ControllerMpd::pushState
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::servicePushState
Feb 18 21:46:03 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:46:03 volumio volumio[1368]: 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","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""}
Feb 18 21:46:03 volumio volumio[1368]: verbose: CURRENT POSITION 0
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::syncState stateService play
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::syncState currentStatus stop
Feb 18 21:46:03 volumio volumio[1368]: info: ControllerMpd::pushState
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::servicePushState
Feb 18 21:46:03 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:46:03 volumio volumio[1368]: 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","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""}
Feb 18 21:46:03 volumio volumio[1368]: verbose: CURRENT POSITION 0
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::syncState stateService play
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::syncState currentStatus play
Feb 18 21:46:03 volumio volumio[1368]: info: Received an update from plugin. extracting info from payload
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::pushState
Feb 18 21:46:03 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::pushState
Feb 18 21:46:03 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState
Feb 18 21:46:03 volumio volumio[1368]: info: ------------------------------ 13ms
Feb 18 21:46:03 volumio volumio5-onboarding[1860]: time=2026-02-18T21:46:03.880+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" state=STATUS_PLAYING positionMs=0 volume=100
Feb 18 21:46:03 volumio volumio5-onboarding[1860]: time=2026-02-18T21:46:03.880+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title=mp3
Feb 18 21:46:03 volumio volumio5-onboarding[1860]: time=2026-02-18T21:46:03.880+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" state=STATUS_PLAYING positionMs=0 volume=100
Feb 18 21:46:03 volumio volumio5-onboarding[1860]: time=2026-02-18T21:46:03.880+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title=mp3
Feb 18 21:46:03 volumio volumio[1368]: info: ------------------------------ 10ms
Feb 18 21:46:03 volumio volumio[1368]: info: FusionDsp - Volumio is playing
Feb 18 21:46:03 volumio volumio[1368]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Feb 18 21:46:03 volumio volumio[1368]: info: FusionDsp - Volumio is playing
Feb 18 21:46:03 volumio volumio[1368]: info: FusionDsp - Clipping Monitor started
Feb 18 21:46:03 volumio volumio[1368]: info: FusionDsp - Clipping Monitor started
Feb 18 21:46:03 volumio volumio[1368]: info:
Feb 18 21:46:03 volumio volumio[1368]: ---------------------------- MPD announces system playlist update
Feb 18 21:46:03 volumio volumio[1368]: info: Ignoring MPD Status Update
Feb 18 21:46:03 volumio volumio[1368]: info:
Feb 18 21:46:03 volumio volumio[1368]: ---------------------------- MPD announces state update: player
Feb 18 21:46:03 volumio volumio[1368]: info: ControllerMpd::getState
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand status
Feb 18 21:46:03 volumio volumio[1368]: info: ------------------------------ 5ms
Feb 18 21:46:03 volumio volumio[1368]: info: sendMpdCommand status took 0 milliseconds
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::parseState
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 18 21:46:03 volumio volumio[1368]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 18 21:46:03 volumio volumio[1368]: verbose: ControllerMpd::parseTrackInfo
Feb 18 21:46:03 volumio volumio[1368]: info: ControllerMpd::pushState
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::servicePushState
Feb 18 21:46:03 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:46:03 volumio volumio[1368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1339,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"JAX JONES & MNEK - WHERE DID YOU GO?","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""}
Feb 18 21:46:03 volumio volumio[1368]: verbose: CURRENT POSITION 0
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::syncState stateService play
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::syncState currentStatus play
Feb 18 21:46:03 volumio volumio[1368]: info: Received an update from plugin. extracting info from payload
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::pushState
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState
Feb 18 21:46:03 volumio volumio[1368]: info: CoreStateMachine::pushState
Feb 18 21:46:03 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState
Feb 18 21:46:03 volumio volumio5-onboarding[1860]: time=2026-02-18T21:46:03.943+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" state=STATUS_PLAYING positionMs=0 volume=100
Feb 18 21:46:03 volumio volumio5-onboarding[1860]: time=2026-02-18T21:46:03.943+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title="JAX JONES & MNEK - WHERE DID YOU GO?"
Feb 18 21:46:03 volumio volumio5-onboarding[1860]: time=2026-02-18T21:46:03.944+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" state=STATUS_PLAYING positionMs=0 volume=100
Feb 18 21:46:03 volumio volumio5-onboarding[1860]: time=2026-02-18T21:46:03.944+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.229:37316 @ 0xc00028a2d0" id=https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 title="JAX JONES & MNEK - WHERE DID YOU GO?"
Feb 18 21:46:03 volumio volumio[1368]: info: ------------------------------ 11ms
Feb 18 21:46:03 volumio volumio[1368]: info: FusionDsp - Volumio is playing
Feb 18 21:46:03 volumio volumio[1368]: info: FusionDsp - Volumio is playing
Feb 18 21:46:04 volumio volumio[1368]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 18 21:46:04 volumio volumio[1368]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 18 21:46:04 volumio volumio[1368]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 18 21:46:04 volumio volumio[1368]: info: FusionDsp - Nulleq applied
Feb 18 21:46:04 volumio volumio[1368]: error: FusionDsp - Error in eqv! Cannot split values!
Feb 18 21:46:04 volumio volumio[1368]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"}
Feb 18 21:46:19 volumio volumio[1368]: info: CoreCommandRouter::volumioSeek
Feb 18 21:46:19 volumio volumio[1368]: info: CoreStateMachine::seek
Feb 18 21:46:19 volumio volumio[1368]: info: CorePlayQueue::getTrack 0
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 18 21:46:30 volumio volumio[1368]: info: Retrieving Cloud Streaming UI
Feb 18 21:46:30 volumio volumio[1368]: info: Getting Tidal Cloud Configuration
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 18 21:46:30 volumio volumio[1368]: info: Getting Qobuz Cloud Configuration
Feb 18 21:46:30 volumio volumio[1368]: info: Asking plugin for UI Config
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 18 21:46:30 volumio volumio[1368]: info: Getting Spotify Cloud Configuration
Feb 18 21:46:30 volumio volumio[1368]: info: Asking plugin for UI Config
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 18 21:46:30 volumio volumio[1368]: info: Saving Spotify Acccount
Feb 18 21:46:30 volumio volumio[1368]: info: Got Tidal Cloud Configuration
Feb 18 21:46:30 volumio volumio[1368]: info: Got it
Feb 18 21:46:30 volumio volumio[1368]: info: Got it
Feb 18 21:46:30 volumio volumio[1368]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 18 21:46:30 volumio volumio[1368]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave')
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Feb 18 21:46:30 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Feb 18 21:46:32 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Feb 18 21:46:32 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Feb 18 21:46:34 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Feb 18 21:46:38 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Feb 18 21:46:42 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Feb 18 21:46:43 volumio volumio[1368]: info: Disabling MyMusic plugin upnp
Feb 18 21:46:43 volumio sudo[3132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Feb 18 21:46:43 volumio sudo[3132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 18 21:46:43 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD...
Feb 18 21:46:44 volumio volumio[1368]: info: Enabling MyMusic plugin upnp
Feb 18 21:46:44 volumio volumio[1368]: info: Enabling plugin upnp
Feb 18 21:46:44 volumio volumio[1368]: info: Loading plugin "upnp"...
Feb 18 21:46:44 volumio volumio[1368]: info: [1771447604080] Starting Upmpd Daemon
Feb 18 21:46:44 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 18 21:46:44 volumio volumio[1368]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 18 21:46:44 volumio volumio[1368]: Error: listen EADDRINUSE: address already in use :::6599
Feb 18 21:46:44 volumio volumio[1368]: at Server.setupListenHandle [as _listen2] (node:net:1872:16)
Feb 18 21:46:44 volumio volumio[1368]: at listenInCluster (node:net:1920:12)
Feb 18 21:46:44 volumio volumio[1368]: at Server.listen (node:net:2008:7)
Feb 18 21:46:44 volumio volumio[1368]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Feb 18 21:46:44 volumio volumio[1368]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Feb 18 21:46:44 volumio volumio[1368]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Feb 18 21:46:44 volumio volumio[1368]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Feb 18 21:46:44 volumio volumio[1368]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) {
Feb 18 21:46:44 volumio volumio[1368]: code: 'EADDRINUSE',
Feb 18 21:46:44 volumio volumio[1368]: errno: -98,
Feb 18 21:46:44 volumio volumio[1368]: syscall: 'listen',
Feb 18 21:46:44 volumio volumio[1368]: address: '::',
Feb 18 21:46:44 volumio volumio[1368]: port: 6599
Feb 18 21:46:44 volumio volumio[1368]: }
Feb 18 21:46:44 volumio volumio[1368]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 18 21:46:44 volumio sudo[3151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 21:45'
Feb 18 21:46:44 volumio sudo[3151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Debian GNU/Linux 12 (bookworm)"
NAME="Debian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026"
VOLUMIO_VERSION="4.096"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"