-- Logs begin at Wed 2026-02-11 03:25:41 +08, end at Thu 2026-03-26 14:52:42 +08. -- Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.099+08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.175:52896 Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.358+08:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.175:52896 @ 0x29362a0" latency=229.724261ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.365+08:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.175:52896 @ 0x29362a0" latency=230.920303ms timeout=10s Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.365+08:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" Mar 26 14:52:20 volumio volumio[998]: info: Received Get System Info Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 14:52:20 volumio volumio[998]: info: Discovery: Getting this device information Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Mar 26 14:52:20 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.375+08:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" name=Volumio Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.379+08:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" language=en Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.384+08:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" timezone=Asia/Kuala_Lumpur Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.386+08:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" available=true connected=false macAddress= ip4Address= ip6Address= Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.390+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" available=true connected=true macAddress=b8:27:eb:76:21:39 ip4Address=192.168.0.51/24 ip6Address= ssid=Kampungku Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.391+08:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" setupComplete=true Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 26 14:52:20 volumio volumio[998]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 26 14:52:20 volumio volumio[998]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 26 14:52:20 volumio volumio[998]: Card hw:1 'Headphones'/'bcm2835 Headphones' Mar 26 14:52:20 volumio volumio[998]: amixer -c 2 info | grep "snd_rpi_hifiberry_dacplus" Mar 26 14:52:20 volumio volumio[998]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplus' Mar 26 14:52:20 volumio volumio[998]: amixer -c 2 info | grep "HiFiBerry DAC" Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.702+08:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" selectedOutputId=2 Mar 26 14:52:20 volumio volumio[998]: info: Received Get System Info Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 14:52:20 volumio volumio[998]: info: Discovery: Getting this device information Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Mar 26 14:52:20 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.732+08:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" currentVersion=3.908 latestVersion=3.912 Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.733+08:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.175:52896 @ 0x29362a0" status=UPDATE_STATUS_NONE progress=0 Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.733+08:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" userId= Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.734+08:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" providers=3 Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.740+08:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" plugins=0 Mar 26 14:52:20 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Mar 26 14:52:20 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.747+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 14:52:20 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:20.748+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:21 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:21.821+08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.175:52896 @ 0x29362a0" latency=281.106498ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 26 14:52:22 volumio sudo[2455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 14:52:22 volumio sudo[2455]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 14:52:22 volumio sudo[2455]: pam_unix(sudo:session): session closed for user root Mar 26 14:52:22 volumio sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 14:52:22 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 14:52:22 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root Mar 26 14:52:22 volumio volumio[998]: verbose: New Socket.io Connection to 192.168.0.51 from 192.168.0.175 UA: Mozilla/5.0 (Linux; Android 16; SM-S908E Build/BP2A.250605.031.A3; 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: 5 Mar 26 14:52:22 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 14:52:23 volumio sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 14:52:23 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 14:52:23 volumio sudo[2461]: pam_unix(sudo:session): session closed for user root Mar 26 14:52:23 volumio sudo[2463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 14:52:23 volumio sudo[2463]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 14:52:23 volumio sudo[2463]: pam_unix(sudo:session): session closed for user root Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 14:52:23 volumio volumio[998]: verbose: New Socket.io Connection to 192.168.0.51 from 192.168.0.175 UA: Mozilla/5.0 (Linux; Android 16; SM-S908E Build/BP2A.250605.031.A3; 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 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::volumioGetVisibleSources Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Mar 26 14:52:23 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::volumioGetQueue Mar 26 14:52:23 volumio volumio[998]: info: CoreStateMachine::getQueue Mar 26 14:52:23 volumio volumio[998]: info: CorePlayQueue::getQueue Mar 26 14:52:23 volumio volumio[998]: info: Listing playlists Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 26 14:52:23 volumio volumio[998]: info: Received Get System Info Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 14:52:23 volumio volumio[998]: info: Discovery: Getting this device information Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Mar 26 14:52:23 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Mar 26 14:52:23 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 26 14:52:23 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 26 14:52:25 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 14:52:25 volumio volumio[998]: info: Received Get System Info Mar 26 14:52:25 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 26 14:52:25 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 26 14:52:25 volumio volumio[998]: info: Discovery: Getting this device information Mar 26 14:52:25 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Mar 26 14:52:25 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:25 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 26 14:52:25 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 26 14:52:26 volumio volumio[998]: info: Preload queue cleared Mar 26 14:52:26 volumio volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::ClearQueue Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::stop Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 14:52:26 volumio volumio[998]: info: CorePlayQueue::clearPlayQueue Mar 26 14:52:26 volumio volumio[998]: info: CorePlayQueue::saveQueue Mar 26 14:52:26 volumio volumio[998]: info: CoreCommandRouter::volumioPushQueue Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::addQueueItems Mar 26 14:52:26 volumio volumio[998]: info: CorePlayQueue::addQueueItems Mar 26 14:52:26 volumio volumio[998]: info: Preload queue cleared Mar 26 14:52:26 volumio volumio[998]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Mar 26 14:52:26 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 14:52:26 volumio volumio[998]: info: CoreCommandRouter::volumioPushQueue Mar 26 14:52:26 volumio volumio[998]: info: CorePlayQueue::saveQueue Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::updateTrackBlock Mar 26 14:52:26 volumio volumio[998]: info: CorePlayQueue::getTrackBlock Mar 26 14:52:26 volumio volumio[998]: info: CoreCommandRouter::volumioPlay Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::play index 0 Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::stop Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::play index undefined Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 14:52:26 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:26 volumio volumio[998]: info: CoreStateMachine::startPlaybackTimer Mar 26 14:52:26 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:26 volumio volumio[998]: info: [1774507946170] ControllerWebradio::clearAddPlayTrack Mar 26 14:52:26 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 14:52:26 volumio volumio[998]: info: sendMpdCommand stop took 7 milliseconds Mar 26 14:52:26 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 14:52:26 volumio volumio[998]: info: Mar 26 14:52:26 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:26 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:26 volumio volumio[998]: info: sendMpdCommand clear took 4 milliseconds Mar 26 14:52:26 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Mar 26 14:52:26 volumio volumio[998]: info: Mar 26 14:52:26 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:26 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:26 volumio volumio[998]: info: Mar 26 14:52:26 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:26 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:26 volumio volumio[998]: error: updateQueue error: null Mar 26 14:52:26 volumio volumio[998]: info: ------------------------------ 13ms Mar 26 14:52:26 volumio volumio[998]: error: Upnp client error: Error: This socket has been ended by the other party Mar 26 14:52:27 volumio volumio[998]: info: Mar 26 14:52:27 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:27 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:27 volumio volumio[998]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 830 milliseconds Mar 26 14:52:27 volumio volumio[998]: info: ------------------------------ 827ms Mar 26 14:52:27 volumio volumio[998]: info: ------------------------------ 826ms Mar 26 14:52:27 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 14:52:27 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand play Mar 26 14:52:27 volumio volumio[998]: info: Mar 26 14:52:27 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:27 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:27 volumio volumio[998]: info: Mar 26 14:52:27 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:27 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:27 volumio volumio[998]: info: ------------------------------ 9ms Mar 26 14:52:27 volumio volumio[998]: info: sendMpdCommand play took 6 milliseconds Mar 26 14:52:27 volumio volumio[998]: info: ------------------------------ 5ms Mar 26 14:52:27 volumio volumio[998]: info: ------------------------------ 4ms Mar 26 14:52:28 volumio volumio[998]: info: CoreCommandRouter::volumioPlay Mar 26 14:52:28 volumio volumio[998]: info: CoreStateMachine::play index undefined Mar 26 14:52:28 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 14:52:28 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:28 volumio volumio[998]: info: CoreStateMachine::startPlaybackTimer Mar 26 14:52:28 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:28 volumio volumio[998]: info: [1774507948616] ControllerWebradio::clearAddPlayTrack Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 14:52:28 volumio volumio[998]: info: Mar 26 14:52:28 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:28 volumio volumio[998]: info: sendMpdCommand stop took 6 milliseconds Mar 26 14:52:28 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 14:52:28 volumio volumio[998]: info: Mar 26 14:52:28 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:28 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:28 volumio volumio[998]: info: Mar 26 14:52:28 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:28 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:28 volumio volumio[998]: info: Mar 26 14:52:28 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:28 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:28 volumio volumio[998]: info: sendMpdCommand status took 9 milliseconds Mar 26 14:52:28 volumio volumio[998]: info: sendMpdCommand clear took 10 milliseconds Mar 26 14:52:28 volumio volumio[998]: info: sendMpdCommand status took 7 milliseconds Mar 26 14:52:28 volumio volumio[998]: info: sendMpdCommand status took 6 milliseconds Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:28 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:28 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:28 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:28 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:28 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:28 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:28 volumio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 26 14:52:28 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:28 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:28 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:28 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:28 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:28 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:28 volumio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 26 14:52:28 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:28.666+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 14:52:28 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:28.667+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:28 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:28.668+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 14:52:28 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:28.669+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:28 volumio volumio[998]: info: ------------------------------ 49ms Mar 26 14:52:28 volumio volumio[998]: info: ------------------------------ 48ms Mar 26 14:52:28 volumio volumio[998]: info: Mar 26 14:52:28 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:28 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:28 volumio volumio[998]: info: Mar 26 14:52:28 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:28 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:28 volumio volumio[998]: error: updateQueue error: null Mar 26 14:52:28 volumio volumio[998]: info: ------------------------------ 52ms Mar 26 14:52:28 volumio volumio[998]: info: sendMpdCommand playlistinfo took 45 milliseconds Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:28 volumio volumio[998]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 26 14:52:28 volumio volumio[998]: info: ------------------------------ 66ms Mar 26 14:52:28 volumio volumio[998]: info: Mar 26 14:52:28 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:28 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:28 volumio volumio[998]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 265 milliseconds Mar 26 14:52:28 volumio volumio[998]: info: ------------------------------ 227ms Mar 26 14:52:28 volumio volumio[998]: info: ------------------------------ 225ms Mar 26 14:52:28 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 14:52:28 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand play Mar 26 14:52:28 volumio volumio[998]: info: Mar 26 14:52:28 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:28 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:28 volumio volumio[998]: info: Mar 26 14:52:28 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:28 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:28 volumio volumio[998]: info: ------------------------------ 8ms Mar 26 14:52:28 volumio volumio[998]: info: sendMpdCommand play took 6 milliseconds Mar 26 14:52:28 volumio volumio[998]: info: ------------------------------ 5ms Mar 26 14:52:28 volumio volumio[998]: info: ------------------------------ 4ms Mar 26 14:52:31 volumio volumio[998]: info: Mar 26 14:52:31 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:31 volumio volumio[998]: info: Mar 26 14:52:31 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:31 volumio volumio[998]: info: Mar 26 14:52:31 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:31 volumio volumio[998]: info: Mar 26 14:52:31 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand status took 21 milliseconds Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand status took 22 milliseconds Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand status took 21 milliseconds Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand status took 3 milliseconds Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: 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":"oldies-but-goldies","artist":"(#1 - 26/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:31 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus stop Mar 26 14:52:31 volumio volumio[998]: info: ------------------------------ 37ms Mar 26 14:52:31 volumio volumio[998]: info: Mar 26 14:52:31 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:31 volumio volumio[998]: info: Mar 26 14:52:31 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand status took 15 milliseconds Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand status took 5 milliseconds Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: 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":"oldies-but-goldies","artist":"(#1 - 26/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:31 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:31 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: 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":"oldies-but-goldies","artist":"(#1 - 26/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:31 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:31 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: 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":"oldies-but-goldies","artist":"(#1 - 26/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:31 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:31 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.921+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=190 volume=80 Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.921+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.924+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=190 volume=80 Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.924+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.925+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=190 volume=80 Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.926+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.927+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=190 volume=80 Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.928+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.930+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=190 volume=80 Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.930+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.932+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=190 volume=80 Mar 26 14:52:31 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:31.932+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:31 volumio volumio[998]: info: ------------------------------ 131ms Mar 26 14:52:31 volumio volumio[998]: info: ------------------------------ 131ms Mar 26 14:52:31 volumio volumio[998]: info: ------------------------------ 115ms Mar 26 14:52:31 volumio volumio[998]: info: sendMpdCommand playlistinfo took 93 milliseconds Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:31 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: 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":"oldies-but-goldies","artist":"(#1 - 26/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:31 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:31 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:31 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: 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":"oldies-but-goldies","artist":"(#1 - 26/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:31 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:31 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:31 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:31 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.013+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.013+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.015+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.016+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.017+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.018+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.020+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.020+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 173ms Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 165ms Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:32 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:32 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:32 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:32 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 12ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand status took 12 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 11ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand status took 9 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 9ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand status took 7 milliseconds Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:32 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:32 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 18ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand status took 15 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 11ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand status took 9 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 9ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand status took 7 milliseconds Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:32 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:32 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:32 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:32 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:32 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:32 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:32 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:32 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:32 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:32 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:32 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:32 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.125+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.126+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.127+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.128+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.130+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.130+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.135+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.136+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.137+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.138+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.140+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=278 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.140+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 107ms Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 105ms Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 103ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand playlistinfo took 83 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand playlistinfo took 82 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand playlistinfo took 81 milliseconds Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:32 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:32 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:32 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:32 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:32 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:32 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:32 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:32 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:32 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:32 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:32 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:32 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.209+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=483 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.210+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.212+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=483 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.212+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.214+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=483 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.215+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.216+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=483 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.217+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.219+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=483 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.219+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.222+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=483 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.223+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 186ms Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 179ms Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 179ms Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:32 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:32 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:32 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:32 volumio volumio[998]: info: Mar 26 14:52:32 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 26ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand status took 25 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 7ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand status took 6 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 5ms Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand status took 4 milliseconds Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 14:52:32 volumio volumio[998]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:32 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:32 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:32 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1186,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:32 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:32 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:32 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:32 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1186,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:32 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:32 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:32 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:32 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1186,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:32 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:32 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:32 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.360+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=600 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.361+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.362+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=600 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.363+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.365+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=600 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.365+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.367+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=600 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.367+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.369+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=600 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.370+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.371+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=600 volume=80 Mar 26 14:52:32 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:32.372+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 122ms Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 103ms Mar 26 14:52:32 volumio volumio[998]: info: ------------------------------ 102ms Mar 26 14:52:33 volumio volumio[998]: info: Mar 26 14:52:33 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:33 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:33 volumio volumio[998]: info: Mar 26 14:52:33 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:33 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:33 volumio volumio[998]: info: Mar 26 14:52:33 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:33 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:33 volumio volumio[998]: info: Mar 26 14:52:33 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:33 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:33 volumio volumio[998]: info: ------------------------------ 8ms Mar 26 14:52:33 volumio volumio[998]: info: sendMpdCommand status took 6 milliseconds Mar 26 14:52:33 volumio volumio[998]: info: ------------------------------ 5ms Mar 26 14:52:33 volumio volumio[998]: info: sendMpdCommand status took 4 milliseconds Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:33 volumio volumio[998]: info: Mar 26 14:52:33 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:33 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:33 volumio volumio[998]: info: Mar 26 14:52:33 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:33 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:33 volumio volumio[998]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 14:52:33 volumio volumio[998]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 14:52:33 volumio volumio[998]: info: ------------------------------ 5ms Mar 26 14:52:33 volumio volumio[998]: info: sendMpdCommand status took 5 milliseconds Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:33 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:33 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:33 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2086,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:33 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:33 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:33 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:33 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:33 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2086,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:33 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:33 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.245+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=1487 volume=80 Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.246+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.248+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=1487 volume=80 Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.248+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.250+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=1487 volume=80 Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.251+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.253+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=1487 volume=80 Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.253+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:33 volumio volumio[998]: info: ------------------------------ 79ms Mar 26 14:52:33 volumio volumio[998]: info: ------------------------------ 77ms Mar 26 14:52:33 volumio volumio[998]: info: sendMpdCommand playlistinfo took 62 milliseconds Mar 26 14:52:33 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:33 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:33 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:33 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2086,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:33 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:33 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:33 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.286+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=1487 volume=80 Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.287+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.289+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=1487 volume=80 Mar 26 14:52:33 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:33.289+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:33 volumio volumio[998]: info: ------------------------------ 95ms Mar 26 14:52:33 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 26 14:52:34 volumio volumio[998]: info: Mar 26 14:52:34 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:34 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:34 volumio volumio[998]: info: Mar 26 14:52:34 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:34 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:34 volumio volumio[998]: info: Mar 26 14:52:34 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:34 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:34 volumio volumio[998]: info: Mar 26 14:52:34 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:34 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:34 volumio volumio[998]: info: ------------------------------ 6ms Mar 26 14:52:34 volumio volumio[998]: info: sendMpdCommand status took 4 milliseconds Mar 26 14:52:34 volumio volumio[998]: info: ------------------------------ 5ms Mar 26 14:52:34 volumio volumio[998]: info: sendMpdCommand status took 3 milliseconds Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:34 volumio volumio[998]: info: Mar 26 14:52:34 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:34 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:34 volumio volumio[998]: info: Mar 26 14:52:34 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:34 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:34 volumio volumio[998]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 14:52:34 volumio volumio[998]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 14:52:34 volumio volumio[998]: info: ------------------------------ 4ms Mar 26 14:52:34 volumio volumio[998]: info: sendMpdCommand status took 3 milliseconds Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:34 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:34 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:34 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3283,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:34 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:34 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:34 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:34 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:34 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3283,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:34 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:34 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.419+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=2625 volume=80 Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.419+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.424+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=2625 volume=80 Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.424+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.426+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=2625 volume=80 Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.427+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.428+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=2625 volume=80 Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.429+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:34 volumio volumio[998]: info: ------------------------------ 66ms Mar 26 14:52:34 volumio volumio[998]: info: ------------------------------ 64ms Mar 26 14:52:34 volumio volumio[998]: info: sendMpdCommand playlistinfo took 53 milliseconds Mar 26 14:52:34 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:34 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:34 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:34 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3283,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aerosmith - Cryin' (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Mar 26 14:52:34 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:34 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:34 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:34 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.463+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=2761 volume=80 Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.464+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.465+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=2761 volume=80 Mar 26 14:52:34 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:34.466+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=http://stream.antenne.de:80/oldies-but-goldies title="Aerosmith - Cryin' (1993)" Mar 26 14:52:34 volumio volumio[998]: info: ------------------------------ 86ms Mar 26 14:52:38 volumio volumio[998]: info: Preload queue cleared Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::ClearQueue Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::stop Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::stPlaybackTimer Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::updateTrackBlock Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrackBlock Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::serviceStop Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::serviceStop Mar 26 14:52:38 volumio volumio[998]: info: [1774507958054] ControllerWebradio::stop Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::clearPlayQueue Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::saveQueue Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::volumioPushQueue Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::addQueueItems Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::addQueueItems Mar 26 14:52:38 volumio volumio[998]: info: Preload queue cleared Mar 26 14:52:38 volumio volumio[998]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 26 14:52:38 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:38.060+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_STOPPED positionMs=0 volume=80 Mar 26 14:52:38 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:38.061+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" title="ANTENNE BAYERN Oldies but Goldies (Germany)" Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::volumioPushQueue Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::saveQueue Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::updateTrackBlock Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrackBlock Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::volumioPlay Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::play index 0 Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::stop Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::play index undefined Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::startPlaybackTimer Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:38 volumio volumio[998]: info: [1774507958081] ControllerWebradio::clearAddPlayTrack Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 14:52:38 volumio volumio[998]: info: Mar 26 14:52:38 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:38 volumio volumio[998]: info: sendMpdCommand stop took 31 milliseconds Mar 26 14:52:38 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:38 volumio volumio[998]: info: sendMpdCommand stop took 6 milliseconds Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 14:52:38 volumio volumio[998]: info: Mar 26 14:52:38 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:38 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:38 volumio volumio[998]: info: Mar 26 14:52:38 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:38 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:38 volumio volumio[998]: info: Mar 26 14:52:38 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:38 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:38 volumio volumio[998]: info: sendMpdCommand status took 7 milliseconds Mar 26 14:52:38 volumio volumio[998]: info: sendMpdCommand clear took 6 milliseconds Mar 26 14:52:38 volumio volumio[998]: info: sendMpdCommand status took 5 milliseconds Mar 26 14:52:38 volumio volumio[998]: info: sendMpdCommand status took 3 milliseconds Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:38 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:38 volumio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 26 14:52:38 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:38 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:38 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:38 volumio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 26 14:52:38 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:38.119+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_STOPPED positionMs=2 volume=80 Mar 26 14:52:38 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:38.120+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Mar 26 14:52:38 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:38.121+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_STOPPED positionMs=2 volume=80 Mar 26 14:52:38 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:38.122+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Mar 26 14:52:38 volumio volumio[998]: info: ------------------------------ 40ms Mar 26 14:52:38 volumio volumio[998]: info: ------------------------------ 39ms Mar 26 14:52:38 volumio volumio[998]: info: Mar 26 14:52:38 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:38 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:38 volumio volumio[998]: info: Mar 26 14:52:38 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:38 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:38 volumio volumio[998]: error: updateQueue error: null Mar 26 14:52:38 volumio volumio[998]: info: ------------------------------ 46ms Mar 26 14:52:38 volumio volumio[998]: info: sendMpdCommand playlistinfo took 44 milliseconds Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:38 volumio volumio[998]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 26 14:52:38 volumio volumio[998]: info: ------------------------------ 57ms Mar 26 14:52:38 volumio volumio[998]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 260 milliseconds Mar 26 14:52:38 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 14:52:38 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand play Mar 26 14:52:38 volumio volumio[998]: info: Mar 26 14:52:38 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:38 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:38 volumio volumio[998]: info: ------------------------------ 230ms Mar 26 14:52:38 volumio volumio[998]: info: ------------------------------ 228ms Mar 26 14:52:38 volumio volumio[998]: info: sendMpdCommand play took 4 milliseconds Mar 26 14:52:38 volumio volumio[998]: info: Mar 26 14:52:38 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:38 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:38 volumio volumio[998]: info: Mar 26 14:52:38 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:38 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:38 volumio volumio[998]: info: ------------------------------ 8ms Mar 26 14:52:38 volumio volumio[998]: info: ------------------------------ 5ms Mar 26 14:52:38 volumio volumio[998]: info: ------------------------------ 3ms Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 26 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 28 milliseconds Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 30 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus stop Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 53ms Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.117+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.117+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.119+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.120+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 65ms Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 37 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 5 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 3 milliseconds Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.160+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.160+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.162+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.162+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 106ms Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 41 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 41 milliseconds Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: 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":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.220+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.221+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.223+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.223+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.225+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.227+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.229+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.230+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.231+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.231+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.233+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=0 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.234+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 163ms Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 130ms Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 129ms Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:40 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:40 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:40 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 10ms Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 7 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 8ms Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:40 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:40 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 16 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 16ms Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 13 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 10ms Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 8 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 7ms Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 5 milliseconds Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.390+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.390+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.392+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.393+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 54ms Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces system playlist update Mar 26 14:52:40 volumio volumio[998]: info: Ignoring MPD Status Update Mar 26 14:52:40 volumio volumio[998]: info: Mar 26 14:52:40 volumio volumio[998]: ---------------------------- MPD announces state update: player Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::getState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 38 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 37 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 37 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 37 milliseconds Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 10ms Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand status took 9 milliseconds Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseState Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.463+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.464+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.465+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.465+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.467+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.468+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.469+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.470+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.472+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.472+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.474+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.474+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.480+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.480+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.482+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=250 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.483+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 160ms Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 158ms Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 152ms Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 150ms Mar 26 14:52:40 volumio volumio[998]: info: sendMpdCommand playlistinfo took 113 milliseconds Mar 26 14:52:40 volumio volumio[998]: verbose: ControllerMpd::parseTrackInfo Mar 26 14:52:40 volumio volumio[998]: info: ControllerMpd::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::servicePushState Mar 26 14:52:40 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Mar 26 14:52:40 volumio volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 26 14:52:40 volumio volumio[998]: verbose: CURRENT POSITION 0 Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState stateService play Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::syncState currentStatus play Mar 26 14:52:40 volumio volumio[998]: info: Received an update from plugin. extracting info from payload Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio[998]: info: CoreStateMachine::pushState Mar 26 14:52:40 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.544+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=442 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.544+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.546+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" state=STATUS_PLAYING positionMs=442 volume=80 Mar 26 14:52:40 volumio volumio5-onboarding[1254]: time=2026-03-26T14:52:40.547+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.175:52896 @ 0x29362a0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 26 14:52:40 volumio volumio[998]: info: ------------------------------ 153ms Mar 26 14:52:41 volumio volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 14:52:41 volumio volumio[998]: TypeError: Cannot read property 'slice' of null Mar 26 14:52:41 volumio volumio[998]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 26 14:52:41 volumio volumio[998]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 26 14:52:41 volumio volumio[998]: at Object.onceWrapper (events.js:422:26) Mar 26 14:52:41 volumio volumio[998]: at ClientRequest.emit (events.js:315:20) Mar 26 14:52:41 volumio volumio[998]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Mar 26 14:52:41 volumio volumio[998]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Mar 26 14:52:41 volumio volumio[998]: at TLSSocket.socketOnData (_http_client.js:509:22) Mar 26 14:52:41 volumio volumio[998]: at TLSSocket.emit (events.js:315:20) Mar 26 14:52:41 volumio volumio[998]: at addChunk (internal/streams/readable.js:309:12) Mar 26 14:52:41 volumio volumio[998]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 26 14:52:41 volumio volumio[998]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Mar 26 14:52:41 volumio volumio[998]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 26 14:52:41 volumio volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 14:52:42 volumio sudo[2558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-26 14:51 Mar 26 14:52:42 volumio sudo[2558]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"