-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sat 2026-03-28 13:16:42 GMT. -- Mar 28 13:15:06 volumio volumio[975]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Mar 28 13:15:06 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:06.039Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:07 volumio volumio[975]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Mar 28 13:15:07 volumio volumio[975]: info: Folder /tmp/plugins removed Mar 28 13:15:07 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:07.930Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:08 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:08.616Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:08 volumio volumio[975]: info: Check plugin dependencies Mar 28 13:15:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 13:15:08 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:08.712Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:08 volumio volumio[975]: info: Checking if plugin already exists Mar 28 13:15:08 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:08.721Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:08 volumio volumio[975]: info: Rename folder Mar 28 13:15:08 volumio volumio[975]: info: Folder /tmp/downloaded_plugin.zip removed Mar 28 13:15:08 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:08.765Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:08 volumio volumio[975]: info: Move to category Mar 28 13:15:09 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:09.395Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:09 volumio volumio[975]: info: Checking if install.sh is present Mar 28 13:15:09 volumio volumio[975]: info: Executing install.sh Mar 28 13:15:09 volumio sudo[1980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Mar 28 13:15:09 volumio sudo[1980]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 13:15:09 volumio volumio[975]: info: Installing Go-librespot Mar 28 13:15:09 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:09.534Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:09 volumio volumio[975]: info: Checking old vollibrespot installs Mar 28 13:15:09 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:09.545Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:09 volumio systemd[1]: Reloading. Mar 28 13:15:09 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 28 13:15:10 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 28 13:15:10 volumio volumio[975]: info: Downloading daemon Mar 28 13:15:10 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:10.435Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:12 volumio volumio[975]: info: Creating directories Mar 28 13:15:12 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:12.705Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:12 volumio volumio[975]: info: Creating data directory Mar 28 13:15:12 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:12.714Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:12 volumio volumio[975]: info: Creating Start Script Mar 28 13:15:12 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:12.728Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:12 volumio systemd[1]: Reloading. Mar 28 13:15:13 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 28 13:15:13 volumio sudo[1980]: pam_unix(sudo:session): session closed for user root Mar 28 13:15:13 volumio volumio[975]: Plugin install end detected on script Mar 28 13:15:13 volumio volumio[975]: info: Install script completed Mar 28 13:15:13 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:13.555Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:13 volumio volumio[975]: info: Adding reference to registry Mar 28 13:15:13 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:13.570Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 13:15:13 volumio volumio[975]: info: Done installing plugin. Mar 28 13:15:13 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 13:15:13 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 13:15:13 volumio volumio[975]: info: Folder /tmp/plugins removed Mar 28 13:15:13 volumio volumio[975]: info: Folder /tmp/downloaded_plugin.zip removed Mar 28 13:15:13 volumio volumio[975]: info: Folder /data/temp removed Mar 28 13:15:15 volumio volumio[975]: info: Enabling plugin spop Mar 28 13:15:15 volumio volumio[975]: info: Loading plugin "spop"... Mar 28 13:15:16 volumio volumio[975]: info: PLUGIN START: spop Mar 28 13:15:16 volumio volumio[975]: info: Creating Spotify config file Mar 28 13:15:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 13:15:16 volumio volumio[975]: info: Done. Mar 28 13:15:16 volumio volumio[975]: info: Spotify config file written Mar 28 13:15:16 volumio volumio[975]: info: No need to fix Spotify hosts Mar 28 13:15:16 volumio sudo[2043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 28 13:15:16 volumio sudo[2043]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 13:15:17 volumio systemd[1]: Started go-librespot Daemon. Mar 28 13:15:17 volumio go-librespot[2045]: go-librespot daemon starting... Mar 28 13:15:17 volumio sudo[2043]: pam_unix(sudo:session): session closed for user root Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=info msg="running go-librespot 0.7.1" Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=debug msg="no app state found" Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=debug msg="stored credentials not found" Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=info msg="generated new device id: 17a5e85c69093e342bf2e524f786883f6f63d8ea" Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=info msg="zeroconf server listening on port 37745" Mar 28 13:15:17 volumio go-librespot[2045]: time="2026-03-28T13:15:17Z" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 28 13:15:20 volumio volumio[975]: info: go-librespot daemon successfully initialized Mar 28 13:15:23 volumio volumio[975]: info: Initializing connection to go-librespot Websocket Mar 28 13:15:23 volumio go-librespot[2045]: time="2026-03-28T13:15:23Z" level=debug msg="new websocket client" Mar 28 13:15:23 volumio volumio[975]: info: Connection to go-librespot Websocket established Mar 28 13:15:26 volumio volumio[975]: info: Getting Spotify volume Mar 28 13:15:26 volumio volumio[975]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 28 13:15:26 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Mar 28 13:15:26 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:33 volumio volumio[975]: info: CoreCommandRouter::volumioGetQueue Mar 28 13:15:33 volumio volumio[975]: info: CoreStateMachine::getQueue Mar 28 13:15:33 volumio volumio[975]: info: CorePlayQueue::getQueue Mar 28 13:15:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 28 13:15:37 volumio volumio[975]: info: Preload queue cleared Mar 28 13:15:41 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 28 13:15:42 volumio volumio[975]: info: Preload queue cleared Mar 28 13:15:54 volumio volumio[975]: info: Preload queue cleared Mar 28 13:15:54 volumio volumio[975]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::ClearQueue Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::stop Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:15:54 volumio volumio[975]: info: CorePlayQueue::clearPlayQueue Mar 28 13:15:54 volumio volumio[975]: info: CorePlayQueue::saveQueue Mar 28 13:15:54 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::addQueueItems Mar 28 13:15:54 volumio volumio[975]: info: CorePlayQueue::addQueueItems Mar 28 13:15:54 volumio volumio[975]: info: Preload queue cleared Mar 28 13:15:54 volumio volumio[975]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323 Mar 28 13:15:54 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 13:15:54 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Mar 28 13:15:54 volumio volumio[975]: info: CorePlayQueue::saveQueue Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Mar 28 13:15:54 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Mar 28 13:15:54 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::play index 0 Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::stop Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::play index undefined Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:15:54 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:15:54 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:54 volumio volumio[975]: info: [1774703754145] ControllerWebradio::clearAddPlayTrack Mar 28 13:15:54 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:15:54 volumio volumio[975]: info: sendMpdCommand stop took 7 milliseconds Mar 28 13:15:54 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 13:15:54 volumio volumio[975]: info: Mar 28 13:15:54 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:54 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:54 volumio volumio[975]: info: sendMpdCommand clear took 4 milliseconds Mar 28 13:15:54 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" Mar 28 13:15:54 volumio volumio[975]: info: Mar 28 13:15:54 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:54 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:54 volumio volumio[975]: info: Mar 28 13:15:54 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:54 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:54 volumio volumio[975]: error: updateQueue error: null Mar 28 13:15:54 volumio volumio[975]: info: ------------------------------ 14ms Mar 28 13:15:54 volumio volumio[975]: error: Upnp client error: Error: This socket has been ended by the other party Mar 28 13:15:54 volumio volumio[975]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" took 272 milliseconds Mar 28 13:15:54 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 13:15:54 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Mar 28 13:15:54 volumio volumio[975]: info: Mar 28 13:15:54 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:54 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:54 volumio volumio[975]: info: ------------------------------ 278ms Mar 28 13:15:54 volumio volumio[975]: info: ------------------------------ 277ms Mar 28 13:15:54 volumio volumio[975]: info: Mar 28 13:15:54 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:54 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:54 volumio volumio[975]: info: Mar 28 13:15:54 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:54 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:54 volumio volumio[975]: info: sendMpdCommand play took 15 milliseconds Mar 28 13:15:54 volumio volumio[975]: info: ------------------------------ 14ms Mar 28 13:15:54 volumio volumio[975]: info: ------------------------------ 9ms Mar 28 13:15:54 volumio volumio[975]: info: ------------------------------ 7ms Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand status took 8 milliseconds Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand status took 6 milliseconds Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand status took 13 milliseconds Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand status took 6 milliseconds Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:56 volumio volumio[975]: 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":"retrodance.mp3","artist":"(#1 - 3860/32000) Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:56 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:56 volumio volumio[975]: 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":"retrodance.mp3","artist":"(#1 - 3860/32000) Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:56 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:56 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:56 volumio volumio[975]: 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":"retrodance.mp3","artist":"(#1 - 3860/32000) Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:56 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:56 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio[975]: info: ------------------------------ 72ms Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.853Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.854Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.854Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.855Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.857Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.858Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.859Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.859Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.860Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.861Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.862Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.862Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.862Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.863Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.863Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.863Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio[975]: info: ------------------------------ 113ms Mar 28 13:15:56 volumio volumio[975]: info: ------------------------------ 112ms Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:56 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:56 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand playlistinfo took 95 milliseconds Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand playlistinfo took 91 milliseconds Mar 28 13:15:56 volumio volumio[975]: info: sendMpdCommand playlistinfo took 91 milliseconds Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:56 volumio volumio[975]: 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 Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:56 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:56 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:56 volumio volumio[975]: 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 Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:56 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:56 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:56 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:56 volumio volumio[975]: 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 Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:56 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:56 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:56 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.950Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.950Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.951Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.951Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.952Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.952Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.953Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.953Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.955Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.955Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.956Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.956Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.957Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.958Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.958Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.959Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.960Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.961Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.962Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.963Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.963Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:56.964Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:56 volumio volumio[975]: info: ------------------------------ 209ms Mar 28 13:15:56 volumio volumio[975]: info: ------------------------------ 199ms Mar 28 13:15:56 volumio volumio[975]: info: ------------------------------ 198ms Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:56 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:56 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:56 volumio volumio[975]: info: Mar 28 13:15:56 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:56 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:56 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 132ms Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand status took 128 milliseconds Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 132ms Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand status took 128 milliseconds Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 34ms Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand status took 32 milliseconds Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 32ms Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand status took 31 milliseconds Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:57 volumio volumio[975]: info: Mar 28 13:15:57 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:57 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:57 volumio volumio[975]: info: Mar 28 13:15:57 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:57 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:57 volumio volumio[975]: info: Mar 28 13:15:57 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:57 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:57 volumio volumio[975]: info: Mar 28 13:15:57 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:57 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 9ms Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand status took 8 milliseconds Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 7ms Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:57 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:57 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Dance Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:57 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:57 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:57 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Dance Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:57 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:57 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:57 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Dance Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:57 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:57 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:57 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Dance Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:57 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:57 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.095Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.097Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.097Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.098Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.098Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.098Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.097Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.098Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.100Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.102Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.102Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.103Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.103Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.104Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.105Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.106Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.104Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.106Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.107Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.108Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.108Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.109Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.110Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.111Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.111Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.112Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.112Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.113Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.114Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.115Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.116Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 262ms Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 262ms Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 164ms Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 163ms Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand playlistinfo took 121 milliseconds Mar 28 13:15:57 volumio volumio[975]: info: sendMpdCommand playlistinfo took 121 milliseconds Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:57 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:57 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:57 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Dance Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:57 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:57 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:57 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:57 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Dance Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:57 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:57 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:57 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.198Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.198Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.199Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.199Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.200Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.200Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.201Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.201Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.202Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.204Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.204Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.205Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=378 volume=100 Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.205Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.206Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.207Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:57.208Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Dance Wave Retro!" Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 193ms Mar 28 13:15:57 volumio volumio[975]: info: ------------------------------ 191ms Mar 28 13:15:59 volumio volumio[975]: info: Mar 28 13:15:59 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:59 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:59 volumio volumio[975]: info: Mar 28 13:15:59 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:59 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:59 volumio volumio[975]: info: Mar 28 13:15:59 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:59 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:59 volumio volumio[975]: info: Mar 28 13:15:59 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:59 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:59 volumio volumio[975]: info: Mar 28 13:15:59 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:15:59 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:15:59 volumio volumio[975]: info: Mar 28 13:15:59 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:15:59 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:15:59 volumio volumio[975]: info: ------------------------------ 9ms Mar 28 13:15:59 volumio volumio[975]: info: sendMpdCommand status took 8 milliseconds Mar 28 13:15:59 volumio volumio[975]: info: ------------------------------ 7ms Mar 28 13:15:59 volumio volumio[975]: info: sendMpdCommand status took 7 milliseconds Mar 28 13:15:59 volumio volumio[975]: info: ------------------------------ 6ms Mar 28 13:15:59 volumio volumio[975]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:15:59 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 13:15:59 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:15:59 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:59 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:15:59 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:59 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3381,"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 Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:59 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:59 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:59 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:59 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3381,"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 Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:59 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:59 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:59 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:15:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:15:59 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3381,"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 Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:15:59 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:15:59 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:59 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:15:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.367Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.368Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.368Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.368Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.369Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.370Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.370Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.370Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.372Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.373Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.373Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.374Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.374Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.375Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.375Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.376Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.377Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.377Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.378Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.378Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.380Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.380Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=2633 volume=100 Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.380Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio5-onboarding[1118]: time=2026-03-28T13:15:59.380Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:15:59 volumio volumio[975]: info: ------------------------------ 109ms Mar 28 13:15:59 volumio volumio[975]: info: ------------------------------ 107ms Mar 28 13:15:59 volumio volumio[975]: info: ------------------------------ 105ms Mar 28 13:16:16 volumio volumio[975]: info: Mar 28 13:16:16 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:16 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:16 volumio volumio[975]: info: Mar 28 13:16:16 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:16 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:16 volumio volumio[975]: info: Mar 28 13:16:16 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:16 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:16 volumio volumio[975]: info: Mar 28 13:16:16 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:16 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:16 volumio volumio[975]: info: Mar 28 13:16:16 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:16 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:16 volumio volumio[975]: info: Mar 28 13:16:16 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:16 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:16 volumio volumio[975]: info: ------------------------------ 16ms Mar 28 13:16:16 volumio volumio[975]: info: sendMpdCommand status took 11 milliseconds Mar 28 13:16:16 volumio volumio[975]: info: ------------------------------ 9ms Mar 28 13:16:16 volumio volumio[975]: info: sendMpdCommand status took 7 milliseconds Mar 28 13:16:16 volumio volumio[975]: info: ------------------------------ 6ms Mar 28 13:16:16 volumio volumio[975]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:16 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:16:16 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:16:16 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:16 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:16 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:16 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:16 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20777,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:16:16 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:16 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:16 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:16 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:16 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20777,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:16:16 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:16 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:16 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:16 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:16 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20777,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:16:16 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:16 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:16 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:16 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.786Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.786Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.787Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.787Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.789Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.790Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.791Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.791Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.792Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.793Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.793Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.794Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.795Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.795Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.795Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.796Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.796Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.796Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.796Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.796Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.798Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.799Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=19911 volume=100 Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.799Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:16.799Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="All about Dance before 2000!" Mar 28 13:16:16 volumio volumio[975]: info: ------------------------------ 121ms Mar 28 13:16:16 volumio volumio[975]: info: ------------------------------ 114ms Mar 28 13:16:16 volumio volumio[975]: info: ------------------------------ 111ms Mar 28 13:16:31 volumio volumio[975]: info: Mar 28 13:16:31 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:31 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:31 volumio volumio[975]: info: Mar 28 13:16:31 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:31 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:31 volumio volumio[975]: info: Mar 28 13:16:31 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:31 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:31 volumio volumio[975]: info: Mar 28 13:16:31 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:31 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:31 volumio volumio[975]: info: Mar 28 13:16:31 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:31 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:31 volumio volumio[975]: info: Mar 28 13:16:31 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:31 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:31 volumio volumio[975]: info: ------------------------------ 14ms Mar 28 13:16:31 volumio volumio[975]: info: sendMpdCommand status took 13 milliseconds Mar 28 13:16:31 volumio volumio[975]: info: ------------------------------ 11ms Mar 28 13:16:31 volumio volumio[975]: info: sendMpdCommand status took 10 milliseconds Mar 28 13:16:31 volumio volumio[975]: info: ------------------------------ 9ms Mar 28 13:16:31 volumio volumio[975]: info: sendMpdCommand status took 8 milliseconds Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:31 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 13:16:31 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 13:16:31 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:31 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:31 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:31 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":35082,"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 Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:16:31 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:31 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:31 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:31 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":35082,"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 Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:16:31 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:31 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:31 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:31 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":35082,"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 Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 28 13:16:31 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:31 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:31 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.150Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.151Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.153Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.153Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.153Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.153Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.154Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.154Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.156Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.156Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.157Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.157Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.158Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.159Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.160Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.160Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.164Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.164Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.165Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.165Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.166Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.167Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=34219 volume=100 Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.167Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:31.167Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://retro.dancewave.online:443/retrodance.mp3 title="Tracklist: https://dancewave.online" Mar 28 13:16:31 volumio volumio[975]: info: ------------------------------ 106ms Mar 28 13:16:31 volumio volumio[975]: info: ------------------------------ 103ms Mar 28 13:16:31 volumio volumio[975]: info: ------------------------------ 99ms Mar 28 13:16:39 volumio volumio[975]: info: Preload queue cleared Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::ClearQueue Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::stop Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::stPlaybackTimer Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::serviceStop Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::serviceStop Mar 28 13:16:39 volumio volumio[975]: info: [1774703799645] ControllerWebradio::stop Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::clearPlayQueue Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::saveQueue Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::addQueueItems Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::addQueueItems Mar 28 13:16:39 volumio volumio[975]: info: Preload queue cleared Mar 28 13:16:39 volumio volumio[975]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.652Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.652Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.653Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" title="Dance Wave Retro!" Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.653Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" title="Dance Wave Retro!" Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::saveQueue Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::play index 0 Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::stop Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::play index undefined Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:39 volumio volumio[975]: info: [1774703799675] ControllerWebradio::clearAddPlayTrack Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:16:39 volumio volumio[975]: info: Mar 28 13:16:39 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:39 volumio volumio[975]: info: sendMpdCommand stop took 35 milliseconds Mar 28 13:16:39 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:39 volumio volumio[975]: info: sendMpdCommand stop took 7 milliseconds Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 13:16:39 volumio volumio[975]: info: Mar 28 13:16:39 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:39 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:39 volumio volumio[975]: info: Mar 28 13:16:39 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:39 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:39 volumio volumio[975]: info: Mar 28 13:16:39 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:39 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:39 volumio volumio[975]: info: sendMpdCommand status took 7 milliseconds Mar 28 13:16:39 volumio volumio[975]: info: sendMpdCommand clear took 6 milliseconds Mar 28 13:16:39 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Mar 28 13:16:39 volumio volumio[975]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:39 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:39 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 13:16:39 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:39 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:39 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:39 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.715Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.716Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.716Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.717Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.717Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.718Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.719Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Mar 28 13:16:39 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:39.719Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Mar 28 13:16:39 volumio volumio[975]: info: ------------------------------ 43ms Mar 28 13:16:39 volumio volumio[975]: info: ------------------------------ 42ms Mar 28 13:16:39 volumio volumio[975]: info: Mar 28 13:16:39 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:39 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:39 volumio volumio[975]: info: Mar 28 13:16:39 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:39 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:39 volumio volumio[975]: error: updateQueue error: null Mar 28 13:16:39 volumio volumio[975]: info: ------------------------------ 45ms Mar 28 13:16:39 volumio volumio[975]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:39 volumio volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 28 13:16:39 volumio volumio[975]: info: ------------------------------ 56ms Mar 28 13:16:39 volumio volumio[975]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 87 milliseconds Mar 28 13:16:39 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 13:16:39 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Mar 28 13:16:39 volumio volumio[975]: info: Mar 28 13:16:39 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:39 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:39 volumio volumio[975]: info: ------------------------------ 56ms Mar 28 13:16:39 volumio volumio[975]: info: ------------------------------ 56ms Mar 28 13:16:39 volumio volumio[975]: info: sendMpdCommand play took 3 milliseconds Mar 28 13:16:39 volumio volumio[975]: info: Mar 28 13:16:39 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:39 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:39 volumio volumio[975]: info: Mar 28 13:16:39 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:39 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:39 volumio volumio[975]: info: ------------------------------ 8ms Mar 28 13:16:39 volumio volumio[975]: info: ------------------------------ 5ms Mar 28 13:16:39 volumio volumio[975]: info: ------------------------------ 3ms Mar 28 13:16:40 volumio volumio[975]: info: Mar 28 13:16:40 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:40 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:40 volumio volumio[975]: info: Mar 28 13:16:40 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:40 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:40 volumio volumio[975]: info: Mar 28 13:16:40 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:40 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:40 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Mar 28 13:16:40 volumio volumio[975]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:16:40 volumio volumio[975]: info: sendMpdCommand status took 3 milliseconds Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:40 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:16:40 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:16:40 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:40 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:40 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:40 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:40 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:40 volumio volumio[975]: 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 28 13:16:40 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:40 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:40 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:16:40 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:40 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:40 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:40 volumio volumio[975]: 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 28 13:16:40 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:40 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:40 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:40 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:40 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:40 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:40 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:40 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:16:40 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:41 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:41 volumio volumio[975]: 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 28 13:16:41 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:41 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:41 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio[975]: info: ------------------------------ 44ms Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.021Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.021Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.022Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.022Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.024Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.025Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.026Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.026Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.027Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.027Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.028Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.028Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.029Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.030Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=0 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.030Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.030Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 28 13:16:41 volumio volumio[975]: info: ------------------------------ 77ms Mar 28 13:16:41 volumio volumio[975]: info: ------------------------------ 76ms Mar 28 13:16:41 volumio volumio[975]: info: Mar 28 13:16:41 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:41 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:41 volumio volumio[975]: info: Mar 28 13:16:41 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:41 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:41 volumio volumio[975]: info: Mar 28 13:16:41 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:41 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:41 volumio volumio[975]: info: Mar 28 13:16:41 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:41 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:41 volumio volumio[975]: info: Mar 28 13:16:41 volumio volumio[975]: ---------------------------- MPD announces system playlist update Mar 28 13:16:41 volumio volumio[975]: info: Ignoring MPD Status Update Mar 28 13:16:41 volumio volumio[975]: info: Mar 28 13:16:41 volumio volumio[975]: ---------------------------- MPD announces state update: player Mar 28 13:16:41 volumio volumio[975]: info: ControllerMpd::getState Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:16:41 volumio volumio[975]: info: ------------------------------ 23ms Mar 28 13:16:41 volumio volumio[975]: info: sendMpdCommand status took 15 milliseconds Mar 28 13:16:41 volumio volumio[975]: info: ------------------------------ 14ms Mar 28 13:16:41 volumio volumio[975]: info: sendMpdCommand status took 12 milliseconds Mar 28 13:16:41 volumio volumio[975]: info: ------------------------------ 11ms Mar 28 13:16:41 volumio volumio[975]: info: sendMpdCommand status took 10 milliseconds Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::parseState Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:16:41 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:16:41 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:16:41 volumio volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:41 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:16:41 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:41 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:41 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 28 13:16:41 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:41 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:41 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:41 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 28 13:16:41 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:41 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio[975]: info: ControllerMpd::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::servicePushState Mar 28 13:16:41 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Mar 28 13:16:41 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 28 13:16:41 volumio volumio[975]: verbose: CURRENT POSITION 0 Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Mar 28 13:16:41 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio[975]: info: CoreStateMachine::pushState Mar 28 13:16:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.122Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.122Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.123Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.124Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.124Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.125Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.125Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.126Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.127Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.127Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.128Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.128Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.130Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.130Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.131Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.132Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.133Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.134Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.134Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.135Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.138Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.139Z level=INFO msg="emitting player state changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" state=STATUS_PLAYING positionMs=124 volume=100 Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.140Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.20.26:50511 @ 0x2c01050" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio5-onboarding[1118]: time=2026-03-28T13:16:41.140Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.26:50511 @ 0x2ca7c20" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 28 13:16:41 volumio volumio[975]: info: ------------------------------ 125ms Mar 28 13:16:41 volumio volumio[975]: info: ------------------------------ 119ms Mar 28 13:16:41 volumio volumio[975]: info: ------------------------------ 117ms Mar 28 13:16:41 volumio volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 13:16:41 volumio volumio[975]: TypeError: Cannot read property 'slice' of null Mar 28 13:16:41 volumio volumio[975]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 28 13:16:41 volumio volumio[975]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 28 13:16:41 volumio volumio[975]: at Object.onceWrapper (events.js:422:26) Mar 28 13:16:41 volumio volumio[975]: at ClientRequest.emit (events.js:315:20) Mar 28 13:16:41 volumio volumio[975]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Mar 28 13:16:41 volumio volumio[975]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Mar 28 13:16:41 volumio volumio[975]: at TLSSocket.socketOnData (_http_client.js:509:22) Mar 28 13:16:41 volumio volumio[975]: at TLSSocket.emit (events.js:315:20) Mar 28 13:16:41 volumio volumio[975]: at addChunk (internal/streams/readable.js:309:12) Mar 28 13:16:41 volumio volumio[975]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 28 13:16:41 volumio volumio[975]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Mar 28 13:16:41 volumio volumio[975]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 28 13:16:41 volumio volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 13:16:42 volumio sudo[2332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-28 13:15 Mar 28 13:16:42 volumio sudo[2332]: 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="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"