Mar 29 11:21:03 volumio systemd[1]: Starting apt-daily.service - Daily apt download activities... Mar 29 11:21:04 volumio systemd[1]: apt-daily.service: Deactivated successfully. Mar 29 11:21:04 volumio systemd[1]: Finished apt-daily.service - Daily apt download activities. Mar 29 11:21:04 volumio systemd[1]: apt-daily.service: Consumed 1.078s CPU time. Mar 29 11:21:10 volumio go-librespot[1601]: time="2026-03-29T11:21:10+02:00" level=trace msg="sent dealer ping" Mar 29 11:21:10 volumio go-librespot[1601]: time="2026-03-29T11:21:10+02:00" level=trace msg="received dealer pong" Mar 29 11:21:40 volumio go-librespot[1601]: time="2026-03-29T11:21:40+02:00" level=trace msg="sent dealer ping" Mar 29 11:21:40 volumio go-librespot[1601]: time="2026-03-29T11:21:40+02:00" level=trace msg="received dealer pong" Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.085+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.188.23:48300 Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.106+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.188.23:48300 @ 0x2800270" latency=-495.972074ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.106+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.188.23:48300 @ 0x2800270" latency=-496.331929ms timeout=10s Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.106+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" Mar 29 11:22:03 volumio volumio[1214]: info: Received Get System Info Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 11:22:03 volumio volumio[1214]: info: Discovery: Getting this device information Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.111+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" name=Volumio Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.112+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" language=de Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.114+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" timezone=Europe/Berlin Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.115+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" available=true connected=false macAddress= ip4Address= ip6Address= Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.118+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" available=true connected=true macAddress=d8:3a:dd:87:21:02 ip4Address=192.168.188.100/24 ip6Address= ssid=Odin Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.118+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" setupComplete=true Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 11:22:03 volumio volumio[1214]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 29 11:22:03 volumio volumio[1214]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 29 11:22:03 volumio volumio[1214]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 29 11:22:03 volumio volumio[1214]: amixer -c 2 info | grep "vc4-hdmi-0" Mar 29 11:22:03 volumio volumio[1214]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Mar 29 11:22:03 volumio volumio[1214]: amixer -c 3 info | grep "vc4-hdmi-1" Mar 29 11:22:03 volumio volumio[1214]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Mar 29 11:22:03 volumio volumio[1214]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 4 Mar 29 11:22:03 volumio volumio[1214]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 29 11:22:03 volumio volumio[1214]: {"cmd":"/usr/local/bin/alsacap -C 4","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 4\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 29 11:22:03 volumio volumio[1214]: amixer -c 4 info | grep "HiFiBerry Digi" Mar 29 11:22:03 volumio volumio[1214]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 4 Mar 29 11:22:03 volumio volumio[1214]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 29 11:22:03 volumio volumio[1214]: {"cmd":"/usr/local/bin/alsacap -C 4","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 4\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 29 11:22:03 volumio volumio[1214]: amixer -c 4 info | grep "snd_rpi_hifiberry_digi" Mar 29 11:22:03 volumio volumio[1214]: Card sysdefault:4 'sndrpihifiberry'/'snd_rpi_hifiberry_digi' Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.268+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" selectedOutputId=4 Mar 29 11:22:03 volumio volumio[1214]: info: Received Get System Info Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 11:22:03 volumio volumio[1214]: info: Discovery: Getting this device information Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.293+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" currentVersion=4.103 latestVersion=4.119 Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.294+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.188.23:48300 @ 0x2800270" status=UPDATE_STATUS_NONE progress=0 Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.294+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" userId= Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.294+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" providers=3 Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.294+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" plugins=62 Mar 29 11:22:03 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.297+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=16766 volume=100 Mar 29 11:22:03 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:03.298+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:04 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 11:22:04 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 11:22:04 volumio volumio[1214]: info: Discovery: Getting this device information Mar 29 11:22:04 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:04 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 11:22:04 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.188.100:3000 from 192.168.188.23 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Mar 29 11:22:04 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 11:22:04 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 11:22:04 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:04.742+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.188.23:48300 @ 0x2800270" latency=-490.201909ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 29 11:22:06 volumio sudo[12181]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 11:22:06 volumio sudo[12181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 11:22:06 volumio sudo[12181]: pam_unix(sudo:session): session closed for user root Mar 29 11:22:06 volumio sudo[12183]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 11:22:06 volumio sudo[12183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 11:22:06 volumio sudo[12183]: pam_unix(sudo:session): session closed for user root Mar 29 11:22:06 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.188.100 from 192.168.188.23 UA: Mozilla/5.0 (Linux; Android 15; moto g34 5G Build/V1UGS35H.75-14-3-7; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 29 11:22:06 volumio sudo[12188]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 11:22:06 volumio sudo[12188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 11:22:06 volumio sudo[12188]: pam_unix(sudo:session): session closed for user root Mar 29 11:22:06 volumio sudo[12190]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 11:22:06 volumio sudo[12190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 11:22:06 volumio sudo[12190]: pam_unix(sudo:session): session closed for user root Mar 29 11:22:06 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.188.100 from 192.168.188.23 UA: Mozilla/5.0 (Linux; Android 15; moto g34 5G Build/V1UGS35H.75-14-3-7; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 11:22:07 volumio volumio[1214]: info: Received Get System Info Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 11:22:07 volumio volumio[1214]: info: Discovery: Getting this device information Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:07 volumio volumio[1214]: info: Listing playlists Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 11:22:07 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 11:22:08 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 11:22:08 volumio volumio[1214]: info: Received Get System Info Mar 29 11:22:08 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 11:22:08 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 11:22:08 volumio volumio[1214]: info: Discovery: Getting this device information Mar 29 11:22:08 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:08 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 11:22:08 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 11:22:09 volumio volumio[1214]: info: CoreCommandRouter::volumioGetQueue Mar 29 11:22:09 volumio volumio[1214]: info: CoreStateMachine::getQueue Mar 29 11:22:09 volumio volumio[1214]: info: CorePlayQueue::getQueue Mar 29 11:22:09 volumio go-librespot[1601]: time="2026-03-29T11:22:09+02:00" level=trace msg="received accesspoint ping" Mar 29 11:22:09 volumio go-librespot[1601]: time="2026-03-29T11:22:09+02:00" level=trace msg="received accesspoint pong ack" Mar 29 11:22:10 volumio go-librespot[1601]: time="2026-03-29T11:22:10+02:00" level=trace msg="sent dealer ping" Mar 29 11:22:10 volumio go-librespot[1601]: time="2026-03-29T11:22:10+02:00" level=trace msg="received dealer pong" Mar 29 11:22:10 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 11:22:10 volumio volumio[1214]: info: Received Get System Info Mar 29 11:22:10 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 11:22:10 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 11:22:10 volumio volumio[1214]: info: Discovery: Getting this device information Mar 29 11:22:10 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:10 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 11:22:15 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 29 11:22:15 volumio volumio[1214]: info: Preload queue cleared Mar 29 11:22:16 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 11:22:18 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 29 11:22:18 volumio volumio[1214]: info: Preload queue cleared Mar 29 11:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 29 11:22:20 volumio volumio[1214]: info: Preload queue cleared Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 11:22:25 volumio volumio[1214]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 11:22:25 volumio volumio[1214]: info: Received Get System Version Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 11:22:25 volumio volumio[1214]: info: Received Get System Info Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 11:22:25 volumio volumio[1214]: info: Discovery: Getting this device information Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 11:22:25 volumio volumio[1214]: info: Preload queue cleared Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::ClearQueue Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::stop Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::updateTrackBlock Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::getTrackBlock Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::stPlaybackTimer Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::serviceStop Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::serviceStop Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 11:22:25 volumio volumio[1214]: info: ControllerMpd::stop Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::clearPlayQueue Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::saveQueue Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushQueue Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::addQueueItems Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::addQueueItems Mar 29 11:22:25 volumio volumio[1214]: info: Preload queue cleared Mar 29 11:22:25 volumio volumio[1214]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s324422 Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.259+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.260+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio mpd[1548]: player: played "http://192.168.188.92:8096/Audio/3beb52726c351145165e847fc6590935/stream.flac?static=true&mediaSourceId=3beb52726c351145165e847fc6590935&tag=f9186fbe946a72ec52bafca6d26a5996&t.flac" Mar 29 11:22:25 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:25 volumio volumio[1214]: info: Mar 29 11:22:25 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand stop took 38 milliseconds Mar 29 11:22:25 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:25 volumio volumio[1214]: info: Mar 29 11:22:25 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:25 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:25 volumio volumio[1214]: info: Mar 29 11:22:25 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:25 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand status took 5 milliseconds Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand status took 3 milliseconds Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand status took 2 milliseconds Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand status took 2 milliseconds Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:25 volumio volumio[1214]: info: ControllerMpd::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:25 volumio volumio[1214]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Land of Sunshine","artist":"Faith No More","album":"Angel Dust","uri":"http://192.168.188.92:8096/Audio/3beb52726c351145165e847fc6590935/stream.flac?static=true&mediaSourceId=3beb52726c351145165e847fc6590935&tag=f9186fbe946a72ec52bafca6d26a5996&t.flac","trackType":"flac"} Mar 29 11:22:25 volumio volumio[1214]: verbose: CURRENT POSITION 0 Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::syncState stateService stop Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio[1214]: info: No code Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio[1214]: info: ControllerMpd::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:25 volumio volumio[1214]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Land of Sunshine","artist":"Faith No More","album":"Angel Dust","uri":"http://192.168.188.92:8096/Audio/3beb52726c351145165e847fc6590935/stream.flac?static=true&mediaSourceId=3beb52726c351145165e847fc6590935&tag=f9186fbe946a72ec52bafca6d26a5996&t.flac","trackType":"flac"} Mar 29 11:22:25 volumio volumio[1214]: verbose: CURRENT POSITION 0 Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::syncState stateService stop Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio[1214]: info: No code Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio[1214]: info: ControllerMpd::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:25 volumio volumio[1214]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Land of Sunshine","artist":"Faith No More","album":"Angel Dust","uri":"http://192.168.188.92:8096/Audio/3beb52726c351145165e847fc6590935/stream.flac?static=true&mediaSourceId=3beb52726c351145165e847fc6590935&tag=f9186fbe946a72ec52bafca6d26a5996&t.flac","trackType":"flac"} Mar 29 11:22:25 volumio volumio[1214]: verbose: CURRENT POSITION 0 Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::syncState stateService stop Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio[1214]: info: No code Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.327+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.328+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.328+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.329+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.330+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.330+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.332+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.333+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.334+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.334+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.335+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.336+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.337+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.337+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.338+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.339+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.339+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 11:22:25 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:25.340+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:25 volumio volumio[1214]: info: ------------------------------ 80ms Mar 29 11:22:25 volumio volumio[1214]: info: ------------------------------ 79ms Mar 29 11:22:25 volumio volumio[1214]: info: ------------------------------ 78ms Mar 29 11:22:25 volumio volumio[1214]: info: Pushing Favourites {"favourite":false} Mar 29 11:22:25 volumio volumio[1214]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935","favourite":false} Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPushQueue Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::saveQueue Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::updateTrackBlock Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::getTrackBlock Mar 29 11:22:25 volumio volumio[1214]: info: CoreCommandRouter::volumioPlay Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::play index 0 Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::stop Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::play index undefined Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:25 volumio volumio[1214]: info: CoreStateMachine::startPlaybackTimer Mar 29 11:22:25 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:25 volumio volumio[1214]: info: [1774776145622] ControllerWebradio::clearAddPlayTrack Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand stop took 2 milliseconds Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 11:22:25 volumio volumio[1214]: info: Mar 29 11:22:25 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Mar 29 11:22:25 volumio volumio[1214]: info: Ignoring MPD Status Update Mar 29 11:22:25 volumio volumio[1214]: info: sendMpdCommand clear took 1 milliseconds Mar 29 11:22:25 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand load "https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/" Mar 29 11:22:25 volumio volumio[1214]: info: Mar 29 11:22:25 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Mar 29 11:22:25 volumio volumio[1214]: info: Ignoring MPD Status Update Mar 29 11:22:25 volumio volumio[1214]: info: Mar 29 11:22:25 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Mar 29 11:22:25 volumio volumio[1214]: info: Ignoring MPD Status Update Mar 29 11:22:25 volumio volumio[1214]: error: updateQueue error: null Mar 29 11:22:25 volumio volumio[1214]: info: ------------------------------ 4ms Mar 29 11:22:26 volumio volumio[1214]: info: [jellyfin-play]: Reported 'stop' for song: Land of Sunshine (at 15973 ms) Mar 29 11:22:27 volumio mpd[1548]: exception: No such playlist Mar 29 11:22:27 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand add "https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/" Mar 29 11:22:27 volumio volumio[1214]: error: updateQueue error: null Mar 29 11:22:27 volumio volumio[1214]: error: updateQueue error: null Mar 29 11:22:27 volumio volumio[1214]: info: Mar 29 11:22:27 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Mar 29 11:22:27 volumio volumio[1214]: info: Ignoring MPD Status Update Mar 29 11:22:27 volumio volumio[1214]: info: ------------------------------ 2193ms Mar 29 11:22:27 volumio volumio[1214]: info: ------------------------------ 2192ms Mar 29 11:22:27 volumio volumio[1214]: info: sendMpdCommand add "https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/" took 1 milliseconds Mar 29 11:22:27 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 11:22:27 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand play Mar 29 11:22:27 volumio volumio[1214]: info: ------------------------------ 2ms Mar 29 11:22:27 volumio volumio[1214]: info: sendMpdCommand play took 2 milliseconds Mar 29 11:22:27 volumio volumio[1214]: info: Mar 29 11:22:27 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Mar 29 11:22:27 volumio volumio[1214]: info: Ignoring MPD Status Update Mar 29 11:22:27 volumio volumio[1214]: info: Mar 29 11:22:27 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Mar 29 11:22:27 volumio volumio[1214]: info: Ignoring MPD Status Update Mar 29 11:22:27 volumio volumio[1214]: info: ------------------------------ 2ms Mar 29 11:22:27 volumio volumio[1214]: info: ------------------------------ 2ms Mar 29 11:22:29 volumio volumio[1214]: info: Mar 29 11:22:29 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:29 volumio volumio[1214]: info: Mar 29 11:22:29 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:29 volumio mpd[1548]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 29 11:22:29 volumio mpd[1548]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 29 11:22:29 volumio mpd[1548]: player: problems opening audio device while playing "https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/" Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:29 volumio volumio[1214]: info: Mar 29 11:22:29 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:29 volumio volumio[1214]: info: Mar 29 11:22:29 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:29 volumio volumio[1214]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand status took 12 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:29 volumio volumio[1214]: info: Mar 29 11:22:29 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:29 volumio volumio[1214]: info: Mar 29 11:22:29 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:29 volumio volumio[1214]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand status took 18 milliseconds Mar 29 11:22:29 volumio volumio[1214]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand status took 21 milliseconds Mar 29 11:22:29 volumio volumio[1214]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand status took 20 milliseconds Mar 29 11:22:29 volumio volumio[1214]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand status took 21 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand clearerror took 16 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand status took 15 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand status took 13 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand status took 12 milliseconds Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Mar 29 11:22:29 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:29 volumio volumio[1214]: verbose: STATE SERVICE {"status":"pause","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":"","artist":null,"album":null,"uri":"https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/","trackType":""} Mar 29 11:22:29 volumio volumio[1214]: verbose: CURRENT POSITION 0 Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState stateService pause Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.682+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=4017 volume=100 Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.682+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:29 volumio volumio[1214]: info: ------------------------------ 59ms Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand clearerror took 46 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand clearerror took 43 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand clearerror took 42 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand clearerror took 42 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 30 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 30 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 29 11:22:29 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:29 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Mar 29 11:22:29 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:29 volumio volumio[1214]: verbose: STATE SERVICE {"status":"pause","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":"","artist":null,"album":null,"uri":"https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/","trackType":""} Mar 29 11:22:29 volumio volumio[1214]: verbose: CURRENT POSITION 0 Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState stateService pause Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Mar 29 11:22:29 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:29 volumio volumio[1214]: verbose: STATE SERVICE {"status":"pause","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":"","artist":null,"album":null,"uri":"https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/","trackType":""} Mar 29 11:22:29 volumio volumio[1214]: verbose: CURRENT POSITION 0 Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState stateService pause Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Mar 29 11:22:29 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:29 volumio volumio[1214]: verbose: STATE SERVICE {"status":"pause","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":"","artist":null,"album":null,"uri":"https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/","trackType":""} Mar 29 11:22:29 volumio volumio[1214]: verbose: CURRENT POSITION 0 Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState stateService pause Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Mar 29 11:22:29 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:29 volumio volumio[1214]: verbose: STATE SERVICE {"status":"pause","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":"","artist":null,"album":null,"uri":"https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/","trackType":""} Mar 29 11:22:29 volumio volumio[1214]: verbose: CURRENT POSITION 0 Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState stateService pause Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:29 volumio volumio[1214]: info: ControllerMpd::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Mar 29 11:22:29 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:29 volumio volumio[1214]: verbose: STATE SERVICE {"status":"pause","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":"","artist":null,"album":null,"uri":"https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/","trackType":""} Mar 29 11:22:29 volumio volumio[1214]: verbose: CURRENT POSITION 0 Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState stateService pause Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Mar 29 11:22:29 volumio volumio[1214]: info: CoreStateMachine::pushState Mar 29 11:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.713+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=4017 volume=100 Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.713+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.715+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=4017 volume=100 Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.715+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.716+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=4017 volume=100 Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.716+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.718+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=4017 volume=100 Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.719+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.719+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" state=STATUS_PAUSED positionMs=4017 volume=100 Mar 29 11:22:29 volumio volumio5-onboarding[1538]: time=2026-03-29T11:22:29.719+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.188.23:48300 @ 0x2800270" id="jellyfin/MusikDLNA@b790d1214c3c4cbc8f92cf0d21fd77e4/song@songId=3beb52726c351145165e847fc6590935" title="Land of Sunshine" Mar 29 11:22:29 volumio volumio[1214]: info: ------------------------------ 120ms Mar 29 11:22:29 volumio volumio[1214]: info: ------------------------------ 116ms Mar 29 11:22:29 volumio volumio[1214]: info: ------------------------------ 116ms Mar 29 11:22:29 volumio volumio[1214]: info: ------------------------------ 107ms Mar 29 11:22:29 volumio volumio[1214]: info: ------------------------------ 107ms Mar 29 11:22:33 volumio volumio[1214]: info: Preload queue cleared Mar 29 11:22:33 volumio volumio[1214]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::ClearQueue Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::stop Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 11:22:33 volumio volumio[1214]: info: CorePlayQueue::clearPlayQueue Mar 29 11:22:33 volumio volumio[1214]: info: CorePlayQueue::saveQueue Mar 29 11:22:33 volumio volumio[1214]: info: CoreCommandRouter::volumioPushQueue Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::addQueueItems Mar 29 11:22:33 volumio volumio[1214]: info: CorePlayQueue::addQueueItems Mar 29 11:22:33 volumio volumio[1214]: info: Preload queue cleared Mar 29 11:22:33 volumio volumio[1214]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s282007 Mar 29 11:22:33 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 29 11:22:33 volumio volumio[1214]: info: CoreCommandRouter::volumioPushQueue Mar 29 11:22:33 volumio volumio[1214]: info: CorePlayQueue::saveQueue Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::updateTrackBlock Mar 29 11:22:33 volumio volumio[1214]: info: CorePlayQueue::getTrackBlock Mar 29 11:22:33 volumio volumio[1214]: info: CoreCommandRouter::volumioPlay Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::play index 0 Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::stop Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::play index undefined Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 11:22:33 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:33 volumio volumio[1214]: info: CoreStateMachine::startPlaybackTimer Mar 29 11:22:33 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Mar 29 11:22:33 volumio volumio[1214]: info: [1774776153268] ControllerWebradio::clearAddPlayTrack Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 11:22:33 volumio mpd[1548]: player: played "https://audiostreams.deluxemusic.tv/2000/mp3-192/tunein/" Mar 29 11:22:33 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:33 volumio volumio[1214]: info: Mar 29 11:22:33 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:33 volumio volumio[1214]: info: sendMpdCommand stop took 9 milliseconds Mar 29 11:22:33 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 11:22:33 volumio volumio[1214]: info: Mar 29 11:22:33 volumio volumio[1214]: ---------------------------- MPD announces system playlist update Mar 29 11:22:33 volumio volumio[1214]: info: Ignoring MPD Status Update Mar 29 11:22:33 volumio volumio[1214]: info: sendMpdCommand status took 2 milliseconds Mar 29 11:22:33 volumio volumio[1214]: info: sendMpdCommand status took 2 milliseconds Mar 29 11:22:33 volumio volumio[1214]: info: sendMpdCommand clear took 1 milliseconds Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::parseState Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand load "https://audiostreams.deluxemusic.tv/music/mp3-192/tunein/" Mar 29 11:22:33 volumio volumio[1214]: info: Mar 29 11:22:33 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:33 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:33 volumio volumio[1214]: info: Mar 29 11:22:33 volumio volumio[1214]: ---------------------------- MPD announces state update: player Mar 29 11:22:33 volumio volumio[1214]: info: ControllerMpd::getState Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Mar 29 11:22:33 volumio volumio[1214]: error: updateQueue error: null Mar 29 11:22:33 volumio volumio[1214]: info: ------------------------------ 6ms Mar 29 11:22:33 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 11:22:33 volumio volumio[1214]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:33 volumio volumio[1214]: verbose: ControllerMpd::parseTrackInfo Mar 29 11:22:33 volumio volumio[1214]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 29 11:22:33 volumio volumio[1214]: info: ------------------------------ 10ms Mar 29 11:22:33 volumio volumio[1214]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 11:22:33 volumio volumio[1214]: TypeError: Cannot read properties of undefined (reading 'split') Mar 29 11:22:33 volumio volumio[1214]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Mar 29 11:22:33 volumio volumio[1214]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 29 11:22:33 volumio volumio[1214]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 29 11:22:33 volumio volumio[1214]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 11:22:33 volumio sudo[12267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 11:21' Mar 29 11:22:33 volumio sudo[12267]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"