Jun 01 13:38:17 volumio volumio[1221]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState Jun 01 13:38:17 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:17 volumio volumio[1221]: info: Restoring Previous Volume level: 100 false false Jun 01 13:38:17 volumio volumio[1221]: info: Output device has changed, restarting MPD Jun 01 13:38:17 volumio sudo[2296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 13:38:17 volumio sudo[2296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 13:38:17 volumio volumio[1221]: info: Output device has changed, restarting Shairport Sync Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 13:38:17 volumio sudo[2296]: pam_unix(sudo:session): session closed for user root Jun 01 13:38:17 volumio volumio[1221]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 13:38:17 volumio volumio[1221]: info: Volume configurations have been set Jun 01 13:38:17 volumio sudo[2299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 13:38:17 volumio sudo[2299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 01 13:38:17 volumio volumio[1221]: info: Updating Volume Controller Parameters: Device: 2 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 01 13:38:17 volumio volumio[1221]: info: Disabling external Volume Control Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:38:17 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:17 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:17 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:17.799+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_STOPPED positionMs=6924 volume=100 Jun 01 13:38:17 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:17.801+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Jun 01 13:38:17 volumio volumio[1221]: info: MPD Permissions set Jun 01 13:38:17 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:17 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 13:38:17 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 13:38:17 volumio systemd[1]: mpd.service: Consumed 1.395s CPU time. Jun 01 13:38:17 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 13:38:17 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 13:38:17 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 13:38:17 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 13:38:17 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:18 volumio sudo[2315]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 13:38:18 volumio sudo[2315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 13:38:18 volumio sudo[2315]: pam_unix(sudo:session): session closed for user root Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 01 13:38:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 13:38:18 volumio volumio[1221]: info: Starting Shairport Sync Jun 01 13:38:18 volumio sudo[2325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 13:38:18 volumio sudo[2325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 13:38:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 13:38:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 13:38:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 13:38:18 volumio systemd[1]: shairport-sync.service: Consumed 1.461s CPU time. Jun 01 13:38:18 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 13:38:18 volumio sudo[2325]: pam_unix(sudo:session): session closed for user root Jun 01 13:38:18 volumio volumio[1221]: info: Shairport-Sync Started Jun 01 13:38:19 volumio mpd[2320]: 2026-06-01T13:38:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 13:38:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 13:38:19 volumio sudo[2299]: pam_unix(sudo:session): session closed for user root Jun 01 13:38:19 volumio volumio[1221]: error: updateQueue error: null Jun 01 13:38:22 volumio volumio[1221]: info: VolumeController::SetAlsaVolume100 Jun 01 13:38:22 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:22 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:22 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:22 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:22.059+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_STOPPED positionMs=6924 volume=100 Jun 01 13:38:22 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:22.060+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Jun 01 13:38:25 volumio volumio[1221]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 01 13:38:25 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 01 13:38:25 volumio volumio[1221]: info: Preparing to save Alsa Options, stopping services first Jun 01 13:38:25 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState Jun 01 13:38:25 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:25 volumio volumio[1221]: info: CoreCommandRouter::volumioStop Jun 01 13:38:25 volumio volumio[1221]: info: CoreStateMachine::stop Jun 01 13:38:25 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 13:38:25 volumio volumio[1221]: info: Saving Audio Output to: {"output_device":{"value":"2","label":"HDMI 0 Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 01 13:38:25 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 13:38:25 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 13:38:25 volumio volumio[1221]: info: Found match in Cards Database: setting mixer for card HDMI 0 Out Jun 01 13:38:26 volumio volumio[1221]: info: Device 2 does not have any Mixer Control Available Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 01 13:38:26 volumio volumio[1221]: info: Updating Volume Controller Parameters: Device: 2 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 01 13:38:26 volumio volumio[1221]: info: Disabling external Volume Control Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:38:26 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:26 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:26 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:26.286+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_STOPPED positionMs=6924 volume=100 Jun 01 13:38:26 volumio volumio[1221]: info: Preparing to generate the ALSA configuration file Jun 01 13:38:26 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:26.288+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 13:38:26 volumio volumio[1221]: info: Asound.conf file unchanged, so no further update is needed Jun 01 13:38:26 volumio volumio[1221]: info: Output device has changed, restarting MPD Jun 01 13:38:26 volumio volumio[1221]: info: Output device has changed, restarting Shairport Sync Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:26 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 13:38:27 volumio sudo[2380]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 13:38:27 volumio sudo[2380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 13:38:27 volumio sudo[2378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 13:38:27 volumio sudo[2378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 13:38:27 volumio sudo[2378]: pam_unix(sudo:session): session closed for user root Jun 01 13:38:27 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 01 13:38:27 volumio volumio[1221]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 13:38:27 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 01 13:38:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 01 13:38:27 volumio systemd[1]: mpd.service: Consumed 1.335s CPU time. Jun 01 13:38:27 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 01 13:38:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 01 13:38:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 01 13:38:27 volumio volumio[1221]: info: MPD Permissions set Jun 01 13:38:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 01 13:38:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 13:38:27 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 13:38:27 volumio sudo[2388]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 13:38:27 volumio sudo[2388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 13:38:27 volumio sudo[2388]: pam_unix(sudo:session): session closed for user root Jun 01 13:38:27 volumio volumio[1221]: info: Starting Shairport Sync Jun 01 13:38:27 volumio sudo[2397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 13:38:27 volumio sudo[2397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 13:38:27 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 01 13:38:27 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 01 13:38:27 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 13:38:27 volumio systemd[1]: shairport-sync.service: Consumed 1.422s CPU time. Jun 01 13:38:27 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 01 13:38:27 volumio sudo[2397]: pam_unix(sudo:session): session closed for user root Jun 01 13:38:27 volumio volumio[1221]: info: Shairport-Sync Started Jun 01 13:38:28 volumio mpd[2395]: 2026-06-01T13:38:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 13:38:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 01 13:38:28 volumio sudo[2380]: pam_unix(sudo:session): session closed for user root Jun 01 13:38:28 volumio volumio[1221]: error: updateQueue error: null Jun 01 13:38:30 volumio volumio[1221]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 01 13:38:30 volumio volumio[1221]: info: CoreStateMachine::getcurrentVolume Jun 01 13:38:30 volumio volumio[1221]: info: CoreCommandRouter::volumioRetrievevolume Jun 01 13:38:30 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:30 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:30 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:30 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:30.249+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_STOPPED positionMs=6924 volume=100 Jun 01 13:38:30 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:30.250+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Jun 01 13:38:30 volumio volumio[1221]: info: CoreCommandRouter::volumioPlay Jun 01 13:38:30 volumio volumio[1221]: info: CoreStateMachine::play index undefined Jun 01 13:38:30 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 13:38:30 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:30 volumio volumio[1221]: info: CoreStateMachine::startPlaybackTimer Jun 01 13:38:30 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:30 volumio volumio[1221]: info: [1780310310800] ControllerWebradio::clearAddPlayTrack Jun 01 13:38:30 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 13:38:30 volumio volumio[1221]: info: sendMpdCommand stop took 0 milliseconds Jun 01 13:38:30 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 13:38:30 volumio volumio[1221]: info: Jun 01 13:38:30 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 01 13:38:30 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 01 13:38:30 volumio volumio[1221]: info: sendMpdCommand clear took 0 milliseconds Jun 01 13:38:30 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Jun 01 13:38:30 volumio volumio[1221]: error: updateQueue error: null Jun 01 13:38:30 volumio volumio[1221]: info: ------------------------------ 1ms Jun 01 13:38:31 volumio volumio[1221]: info: Jun 01 13:38:31 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 01 13:38:31 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 01 13:38:31 volumio volumio[1221]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 258 milliseconds Jun 01 13:38:31 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 13:38:31 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand play Jun 01 13:38:31 volumio volumio[1221]: info: ------------------------------ 1ms Jun 01 13:38:31 volumio volumio[1221]: info: sendMpdCommand play took 11 milliseconds Jun 01 13:38:31 volumio volumio[1221]: info: Jun 01 13:38:31 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:31 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:31 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:31 volumio kernel: ------------[ cut here ]------------ Jun 01 13:38:31 volumio kernel: WARNING: CPU: 3 PID: 2421 at drivers/gpu/drm/vc4/vc4_hdmi.c:726 vc4_hdmi_write_infoframe+0x638/0x754 [vc4] Jun 01 13:38:31 volumio kernel: Packet RAM has to be on to store the packet. Jun 01 13:38:31 volumio kernel: Modules linked in: nls_utf8 cmac algif_hash aes_arm_bs crypto_simd cryptd aes_arm aes_generic algif_skcipher af_alg bnep nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 brcmfmac_wcc xt_tcpudp nft_compat nf_tables nfnetlink brcmfmac 8021q garp stp llc binfmt_misc brcmutil cfg80211 rpi_hevc_dec bcm2835_isp(C) bcm2835_codec(C) bcm2835_v4l2(C) hci_uart btbcm videobuf2_vmalloc bluetooth bcm2835_mmal_vchiq(C) vc_sm_cma(C) v4l2_mem2mem videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 videodev sg raspberrypi_hwmon videobuf2_common ecdh_generic ecc mc rfkill libaes raspberrypi_gpiomem nvmem_rmem uio_pdrv_genirq uio i2c_dev dm_mod ip_tables x_tables ipv6 ads7846 fb_hx8357d(C) fb_st7789v(C) fb_st7735r(C) fb_ili9341(C) fb_ili9340(C) fbtft(C) panel_waveshare_dsi_v2 panel_waveshare_dsi panel_ilitek_ili9881c panel_raspberrypi_touchscreen pwm_bcm2835 spi_bcm2835 snd_soc_bcm2835_i2s squashfs overlay nls_iso8859_1 fuse vc4 snd_soc_hdmi_codec drm_display_helper cec drm_dma_helper v3d gpu_sched Jun 01 13:38:31 volumio kernel: drm_shmem_helper snd_soc_core snd_compress snd_pcm_dmaengine snd_bcm2835(C) drm_kms_helper drm drm_panel_orientation_quirks snd_pcm snd_timer i2c_brcmstb i2c_bcm2835 snd backlight Jun 01 13:38:31 volumio kernel: CPU: 3 UID: 102 PID: 2421 Comm: output:alsa Tainted: G C 6.12.74-v7l+ #1948 Jun 01 13:38:31 volumio kernel: Tainted: [C]=CRAP Jun 01 13:38:31 volumio kernel: Hardware name: BCM2711 Jun 01 13:38:31 volumio kernel: Call trace: Jun 01 13:38:31 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Jun 01 13:38:31 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Jun 01 13:38:31 volumio kernel: dump_stack_lvl from __warn+0x88/0x124 Jun 01 13:38:31 volumio kernel: __warn from warn_slowpath_fmt+0x11c/0x190 Jun 01 13:38:31 volumio kernel: warn_slowpath_fmt from vc4_hdmi_write_infoframe+0x638/0x754 [vc4] Jun 01 13:38:31 volumio kernel: vc4_hdmi_write_infoframe [vc4] from write_device_infoframe+0x9c/0x150 [drm_display_helper] Jun 01 13:38:31 volumio kernel: write_device_infoframe [drm_display_helper] from drm_atomic_helper_connector_hdmi_update_audio_infoframe+0xa4/0xb8 [drm_display_helper] Jun 01 13:38:31 volumio kernel: drm_atomic_helper_connector_hdmi_update_audio_infoframe [drm_display_helper] from vc4_hdmi_audio_prepare+0x13c/0xfe4 [vc4] Jun 01 13:38:31 volumio kernel: vc4_hdmi_audio_prepare [vc4] from hdmi_codec_prepare+0xd8/0x124 [snd_soc_hdmi_codec] Jun 01 13:38:31 volumio kernel: hdmi_codec_prepare [snd_soc_hdmi_codec] from snd_soc_pcm_dai_prepare+0x78/0xf4 [snd_soc_core] Jun 01 13:38:31 volumio kernel: snd_soc_pcm_dai_prepare [snd_soc_core] from __soc_pcm_prepare+0x3c/0xfc [snd_soc_core] Jun 01 13:38:31 volumio kernel: __soc_pcm_prepare [snd_soc_core] from soc_pcm_prepare+0x2c/0x44 [snd_soc_core] Jun 01 13:38:31 volumio kernel: soc_pcm_prepare [snd_soc_core] from snd_pcm_do_prepare+0x2c/0x44 [snd_pcm] Jun 01 13:38:31 volumio kernel: snd_pcm_do_prepare [snd_pcm] from snd_pcm_action_single+0x40/0x80 [snd_pcm] Jun 01 13:38:31 volumio kernel: snd_pcm_action_single [snd_pcm] from snd_pcm_action_nonatomic+0x9c/0xa4 [snd_pcm] Jun 01 13:38:31 volumio kernel: snd_pcm_action_nonatomic [snd_pcm] from sys_ioctl+0x130/0xbcc Jun 01 13:38:31 volumio kernel: sys_ioctl from __sys_trace_return+0x0/0x10 Jun 01 13:38:31 volumio kernel: Exception stack(0xf0e55fa8 to 0xf0e55ff0) Jun 01 13:38:31 volumio kernel: 5fa0: 9a016688 0007c7fc 00000015 00004140 00000000 9a9fd5d8 Jun 01 13:38:31 volumio kernel: 5fc0: 9a016688 0007c7fc 9a9fdae4 00000036 9a016aa8 9a9fdadc 9a9fdc04 9a9fdbdc Jun 01 13:38:31 volumio kernel: 5fe0: 00000000 9a9fd5c0 b1caf000 b1c321e4 Jun 01 13:38:31 volumio kernel: ---[ end trace 0000000000000000 ]--- Jun 01 13:38:31 volumio volumio[1221]: info: Jun 01 13:38:31 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:31 volumio volumio[1221]: info: sendMpdCommand status took 13 milliseconds Jun 01 13:38:31 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:31 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:31 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:31 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:38:31 volumio volumio[1221]: info: Jun 01 13:38:31 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:31 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:31 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:31 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Jun 01 13:38:31 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 13:38:31 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:31 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:38:31 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:31 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:31 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:31 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:31 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:31 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:31 volumio volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 01 13:38:31 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 01 13:38:31 volumio volumio[1221]: info: CoreStateMachine::syncState stateService stop Jun 01 13:38:31 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Jun 01 13:38:31 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:31 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:31 volumio volumio[1221]: info: No code Jun 01 13:38:31 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:31 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:31 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:31 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:31 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:31 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8733/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Jun 01 13:38:31 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 01 13:38:31 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 01 13:38:31 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.008+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=1072 volume=100 Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.009+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.009+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=1072 volume=100 Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.010+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.010+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=1072 volume=100 Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.010+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Jun 01 13:38:32 volumio volumio[1221]: info: ------------------------------ 110ms Jun 01 13:38:32 volumio volumio[1221]: info: ------------------------------ 123ms Jun 01 13:38:32 volumio volumio[1221]: info: sendMpdCommand status took 109 milliseconds Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:32 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:32 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:32 volumio volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 01 13:38:32 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::syncState stateService stop Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::play index undefined Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:32 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.113+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.113+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.135+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_STOPPED positionMs= volume=100 Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.135+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Jun 01 13:38:32 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::startPlaybackTimer Jun 01 13:38:32 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:32 volumio volumio[1221]: info: [1780310312163] ControllerWebradio::clearAddPlayTrack Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 13:38:32 volumio volumio[1221]: info: ------------------------------ 212ms Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:32 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.191+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 13:38:32 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:32.191+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Jun 01 13:38:32 volumio volumio[1221]: info: sendMpdCommand stop took 105 milliseconds Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 13:38:32 volumio volumio[1221]: info: Jun 01 13:38:32 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 01 13:38:32 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 01 13:38:32 volumio volumio[1221]: info: sendMpdCommand clear took 20 milliseconds Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Jun 01 13:38:32 volumio volumio[1221]: error: updateQueue error: null Jun 01 13:38:32 volumio volumio[1221]: info: ------------------------------ 29ms Jun 01 13:38:32 volumio volumio[1221]: info: Jun 01 13:38:32 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 01 13:38:32 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 01 13:38:32 volumio volumio[1221]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 120 milliseconds Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand play Jun 01 13:38:32 volumio volumio[1221]: info: ------------------------------ 6ms Jun 01 13:38:32 volumio volumio[1221]: info: sendMpdCommand play took 5 milliseconds Jun 01 13:38:32 volumio volumio[1221]: info: Jun 01 13:38:32 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:32 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:32 volumio volumio[1221]: info: Jun 01 13:38:32 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:32 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Jun 01 13:38:32 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:38:32 volumio volumio[1221]: info: sendMpdCommand status took 25 milliseconds Jun 01 13:38:32 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:38:32 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:32 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:32 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8733/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Jun 01 13:38:32 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Jun 01 13:38:32 volumio volumio[1221]: info: ------------------------------ 32ms Jun 01 13:38:32 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 13:38:32 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:38:32 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:32 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:32 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8733/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Jun 01 13:38:32 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 01 13:38:32 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 13:38:32 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:32 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:32 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.021+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.022+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.040+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=0 volume=100 Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.041+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Jun 01 13:38:33 volumio volumio[1221]: info: ------------------------------ 103ms Jun 01 13:38:33 volumio volumio[1221]: info: Jun 01 13:38:33 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 01 13:38:33 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 01 13:38:33 volumio volumio[1221]: info: Jun 01 13:38:33 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:33 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:33 volumio volumio[1221]: info: ------------------------------ 72ms Jun 01 13:38:33 volumio volumio[1221]: info: sendMpdCommand status took 89 milliseconds Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:38:33 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:38:33 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:33 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:33 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 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"} Jun 01 13:38:33 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 01 13:38:33 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.193+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=154 volume=100 Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.193+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.220+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=154 volume=100 Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.220+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:33 volumio volumio[1221]: info: ------------------------------ 154ms Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::volumioStop Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::stop Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::stPlaybackTimer Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::updateTrackBlock Jun 01 13:38:33 volumio volumio[1221]: info: CorePlayQueue::getTrackBlock Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:33 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::serviceStop Jun 01 13:38:33 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::serviceStop Jun 01 13:38:33 volumio volumio[1221]: info: [1780310313301] ControllerWebradio::stop Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.303+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_STOPPED positionMs=0 volume=100 Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.303+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Jun 01 13:38:33 volumio volumio[1221]: info: Jun 01 13:38:33 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:33 volumio volumio[1221]: info: sendMpdCommand stop took 49 milliseconds Jun 01 13:38:33 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:33 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 13:38:33 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 01 13:38:33 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:33 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:33 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:33 volumio volumio[1221]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.372+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_STOPPED positionMs=103 volume=100 Jun 01 13:38:33 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:33.372+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Jun 01 13:38:33 volumio volumio[1221]: info: ------------------------------ 50ms Jun 01 13:38:33 volumio volumio[1221]: info: CoreCommandRouter::volumioPlay Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::play index undefined Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 13:38:33 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:33 volumio volumio[1221]: info: CoreStateMachine::startPlaybackTimer Jun 01 13:38:33 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:33 volumio volumio[1221]: info: [1780310313989] ControllerWebradio::clearAddPlayTrack Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 13:38:33 volumio volumio[1221]: info: sendMpdCommand stop took 1 milliseconds Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 13:38:33 volumio volumio[1221]: info: Jun 01 13:38:33 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 01 13:38:33 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 01 13:38:33 volumio volumio[1221]: info: sendMpdCommand clear took 1 milliseconds Jun 01 13:38:33 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Jun 01 13:38:33 volumio volumio[1221]: error: updateQueue error: null Jun 01 13:38:33 volumio volumio[1221]: info: ------------------------------ 2ms Jun 01 13:38:34 volumio volumio[1221]: info: Jun 01 13:38:34 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 01 13:38:34 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 01 13:38:34 volumio volumio[1221]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 117 milliseconds Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 13:38:34 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand play Jun 01 13:38:34 volumio volumio[1221]: info: ------------------------------ 2ms Jun 01 13:38:34 volumio volumio[1221]: info: sendMpdCommand play took 1 milliseconds Jun 01 13:38:34 volumio volumio[1221]: info: Jun 01 13:38:34 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:34 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:34 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:34 volumio volumio[1221]: info: Jun 01 13:38:34 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:34 volumio volumio[1221]: info: Jun 01 13:38:34 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 01 13:38:34 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:34 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:34 volumio volumio[1221]: info: sendMpdCommand status took 2 milliseconds Jun 01 13:38:34 volumio volumio[1221]: info: ControllerMpd::getState Jun 01 13:38:34 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 01 13:38:34 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:34 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:34 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:34 volumio volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 01 13:38:34 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::syncState stateService stop Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:34 volumio volumio[1221]: info: No code Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.514+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=250 volume=100 Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.514+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.564+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=250 volume=100 Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.564+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.565+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=250 volume=100 Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.565+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:34 volumio volumio[1221]: info: ------------------------------ 122ms Jun 01 13:38:34 volumio volumio[1221]: info: sendMpdCommand status took 157 milliseconds Jun 01 13:38:34 volumio volumio[1221]: info: sendMpdCommand status took 148 milliseconds Jun 01 13:38:34 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:34 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 01 13:38:34 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:34 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:34 volumio volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 01 13:38:34 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::syncState stateService stop Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:34 volumio volumio[1221]: info: No code Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:34 volumio volumio[1221]: info: ControllerMpd::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:34 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 01 13:38:34 volumio volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 01 13:38:34 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::syncState stateService stop Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:34 volumio volumio[1221]: info: No code Jun 01 13:38:34 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 01 13:38:34 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.742+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=648 volume=100 Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.743+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.743+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=648 volume=100 Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.743+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.744+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=648 volume=100 Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.745+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.746+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=648 volume=100 Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.746+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.775+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=648 volume=100 Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.775+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.776+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" state=STATUS_PLAYING positionMs=648 volume=100 Jun 01 13:38:34 volumio volumio5-onboarding[1505]: time=2026-06-01T13:38:34.776+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.50.22.17:34570 @ 0x18c2c90" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Jun 01 13:38:34 volumio volumio[1221]: info: ------------------------------ 375ms Jun 01 13:38:34 volumio volumio[1221]: info: ------------------------------ 376ms Jun 01 13:39:16 volumio volumio[1221]: info: Saving local item radio-favourites Jun 01 13:39:17 volumio volumio[1221]: info: Pushing Favourites {"uri":"https://dancewave.online:443/dance.mp3","favourite":false} Jun 01 13:39:22 volumio volumio[1221]: info: Saving local item radio-favourites Jun 01 13:39:22 volumio volumio[1221]: info: Pushing Favourites {"uri":"https://dancewave.online:443/dance.mp3","favourite":false} Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:39:41 volumio volumio[1221]: info: Retrieving Cloud Streaming UI Jun 01 13:39:41 volumio volumio[1221]: info: Getting Tidal Cloud Configuration Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:39:41 volumio volumio[1221]: info: Getting Qobuz Cloud Configuration Jun 01 13:39:41 volumio volumio[1221]: info: Asking plugin for UI Config Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:39:41 volumio volumio[1221]: info: Getting Spotify Cloud Configuration Jun 01 13:39:41 volumio volumio[1221]: info: Asking plugin for UI Config Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:39:41 volumio volumio[1221]: info: Saving Spotify Acccount Jun 01 13:39:41 volumio volumio[1221]: info: Got Tidal Cloud Configuration Jun 01 13:39:41 volumio volumio[1221]: info: Got it Jun 01 13:39:41 volumio volumio[1221]: info: Got it Jun 01 13:39:41 volumio volumio[1221]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 13:39:41 volumio volumio[1221]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 13:39:41 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 13:39:44 volumio volumio[1221]: info: Disabling MyMusic plugin upnp Jun 01 13:39:44 volumio sudo[2528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 01 13:39:44 volumio sudo[2528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 13:39:44 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Jun 01 13:39:45 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 13:39:49 volumio volumio[1221]: info: Enabling MyMusic plugin upnp Jun 01 13:39:49 volumio volumio[1221]: info: Enabling plugin upnp Jun 01 13:39:49 volumio volumio[1221]: info: Loading plugin "upnp"... Jun 01 13:39:49 volumio volumio[1221]: info: [1780310389165] Starting Upmpd Daemon Jun 01 13:39:49 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 13:39:49 volumio volumio[1221]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 13:39:49 volumio volumio[1221]: Error: listen EADDRINUSE: address already in use :::6599 Jun 01 13:39:49 volumio volumio[1221]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Jun 01 13:39:49 volumio volumio[1221]: at listenInCluster (node:net:1920:12) Jun 01 13:39:49 volumio volumio[1221]: at Server.listen (node:net:2008:7) Jun 01 13:39:49 volumio volumio[1221]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jun 01 13:39:49 volumio volumio[1221]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jun 01 13:39:49 volumio volumio[1221]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 01 13:39:49 volumio volumio[1221]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 01 13:39:49 volumio volumio[1221]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Jun 01 13:39:49 volumio volumio[1221]: code: 'EADDRINUSE', Jun 01 13:39:49 volumio volumio[1221]: errno: -98, Jun 01 13:39:49 volumio volumio[1221]: syscall: 'listen', Jun 01 13:39:49 volumio volumio[1221]: address: '::', Jun 01 13:39:49 volumio volumio[1221]: port: 6599 Jun 01 13:39:49 volumio volumio[1221]: } Jun 01 13:39:49 volumio volumio[1221]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 13:39:49 volumio sudo[2559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 13:38' Jun 01 13:39:49 volumio sudo[2559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"