-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Thu 2026-05-28 20:02:12 EEST. -- May 28 20:01:13 volumio volumio[837]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 28 20:01:13 volumio volumio[837]: info: Preparing to save Alsa Options, stopping services first May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::volumioGetState May 28 20:01:13 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::volumioStop May 28 20:01:13 volumio volumio[837]: info: CoreStateMachine::stop May 28 20:01:13 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:13 volumio volumio[837]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 20:01:13 volumio volumio[837]: info: Enabling PI HDMI Output May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 20:01:13 volumio volumio[837]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 28 20:01:13 volumio volumio[837]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 28 20:01:13 volumio volumio[837]: info: Disabling external Volume Control May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 20:01:13 volumio volumio[837]: info: Preparing to generate the ALSA configuration file May 28 20:01:13 volumio volumio[837]: info: Raspberry PI HDMI Output Enabled May 28 20:01:13 volumio sudo[2471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store May 28 20:01:13 volumio sudo[2471]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:01:13 volumio sudo[2471]: pam_unix(sudo:session): session closed for user root May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:01:13 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 28 20:01:14 volumio volumio[837]: info: Alsa Settings successfully stored May 28 20:01:14 volumio volumio[837]: info: VolumeController:: Volume=56 Mute =false May 28 20:01:14 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:14 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:14 volumio volumio[837]: info: Asound.conf file written May 28 20:01:14 volumio sudo[2480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 28 20:01:14 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:01:14 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root May 28 20:01:14 volumio volumio[837]: info: Output device has changed, restarting MPD May 28 20:01:14 volumio volumio[837]: info: Output device has changed, restarting Shairport Sync May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:01:14 volumio sudo[2485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 20:01:14 volumio sudo[2485]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:01:14 volumio sudo[2485]: pam_unix(sudo:session): session closed for user root May 28 20:01:14 volumio sudo[2487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 20:01:14 volumio sudo[2487]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:01:14 volumio systemd[1]: Stopping Music Player Daemon... May 28 20:01:14 volumio volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 20:01:14 volumio volumio[837]: info: Output device has changed, restarting MPD May 28 20:01:14 volumio sudo[2494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 20:01:14 volumio volumio[837]: info: Output device has changed, restarting Shairport Sync May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:01:14 volumio sudo[2494]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:01:14 volumio sudo[2494]: pam_unix(sudo:session): session closed for user root May 28 20:01:14 volumio sudo[2496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 20:01:14 volumio sudo[2496]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:01:14 volumio volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 20:01:14 volumio volumio[837]: info: MPD Permissions set May 28 20:01:14 volumio volumio[837]: info: MPD Permissions set May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio systemd[1]: mpd.service: Succeeded. May 28 20:01:14 volumio systemd[1]: Stopped Music Player Daemon. May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:01:14 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:01:14 volumio volumio[837]: info: Starting Shairport Sync May 28 20:01:14 volumio volumio[837]: info: Starting Shairport Sync May 28 20:01:14 volumio systemd[1]: Starting Music Player Daemon... May 28 20:01:14 volumio sudo[2512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 20:01:14 volumio sudo[2512]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:01:14 volumio sudo[2514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 20:01:14 volumio sudo[2514]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:01:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 28 20:01:14 volumio systemd[1]: shairport-sync.service: Succeeded. May 28 20:01:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 28 20:01:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 28 20:01:15 volumio sudo[2506]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 20:01:15 volumio sudo[2506]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 20:01:15 volumio sudo[2512]: pam_unix(sudo:session): session closed for user root May 28 20:01:15 volumio sudo[2506]: pam_unix(sudo:session): session closed for user root May 28 20:01:15 volumio volumio[837]: info: Shairport-Sync Started May 28 20:01:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 28 20:01:17 volumio mpd[2520]: May 28 20:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 20:01:17 volumio systemd[1]: Started Music Player Daemon. May 28 20:01:17 volumio sudo[2487]: pam_unix(sudo:session): session closed for user root May 28 20:01:17 volumio sudo[2496]: pam_unix(sudo:session): session closed for user root May 28 20:01:17 volumio volumio[837]: error: MPD error: The expression evaluated to a falsy value: May 28 20:01:17 volumio volumio[837]: assert.ok(self.idling) May 28 20:01:17 volumio volumio[837]: error: The expression evaluated to a falsy value: May 28 20:01:17 volumio volumio[837]: assert.ok(self.idling) May 28 20:01:17 volumio volumio[837]: error: updateQueue error: null May 28 20:01:17 volumio volumio[837]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 28 20:01:17 volumio volumio[837]: info: CoreStateMachine::getcurrentVolume May 28 20:01:17 volumio volumio[837]: info: CoreCommandRouter::volumioRetrievevolume May 28 20:01:17 volumio volumio[837]: info: VolumeController:: Volume=56 Mute =false May 28 20:01:17 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:17 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:17 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:17 volumio volumio[837]: info: CoreStateMachine::updateTrackBlock May 28 20:01:17 volumio volumio[837]: info: CorePlayQueue::getTrackBlock May 28 20:01:17 volumio volumio[837]: info: CoreCommandRouter::volumioRetrievevolume May 28 20:01:17 volumio volumio[837]: info: VolumeController:: Volume=56 Mute =false May 28 20:01:17 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:17 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:17 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:18 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 20:01:19 volumio volumio[837]: info: Preload queue cleared May 28 20:01:20 volumio volumio[837]: info: Preload queue cleared May 28 20:01:20 volumio volumio[837]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::ClearQueue May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::stop May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:20 volumio volumio[837]: info: CorePlayQueue::clearPlayQueue May 28 20:01:20 volumio volumio[837]: info: CorePlayQueue::saveQueue May 28 20:01:20 volumio volumio[837]: info: CoreCommandRouter::volumioPushQueue May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::addQueueItems May 28 20:01:20 volumio volumio[837]: info: CorePlayQueue::addQueueItems May 28 20:01:20 volumio volumio[837]: info: Preload queue cleared May 28 20:01:20 volumio volumio[837]: info: Adding Item to queue: https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio May 28 20:01:20 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 20:01:20 volumio volumio[837]: info: CoreCommandRouter::volumioPushQueue May 28 20:01:20 volumio volumio[837]: info: CorePlayQueue::saveQueue May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::updateTrackBlock May 28 20:01:20 volumio volumio[837]: info: CorePlayQueue::getTrackBlock May 28 20:01:20 volumio volumio[837]: info: CoreCommandRouter::volumioPlay May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::play index 0 May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::stop May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::play index undefined May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:20 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:20 volumio volumio[837]: info: CoreStateMachine::startPlaybackTimer May 28 20:01:20 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:20 volumio volumio[837]: info: [1779987680081] ControllerWebradio::clearAddPlayTrack May 28 20:01:20 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:01:20 volumio volumio[837]: info: sendMpdCommand stop took 10 milliseconds May 28 20:01:20 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand clear May 28 20:01:20 volumio volumio[837]: info: May 28 20:01:20 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:20 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:20 volumio volumio[837]: info: sendMpdCommand clear took 7 milliseconds May 28 20:01:20 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand load "https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio" May 28 20:01:20 volumio volumio[837]: info: May 28 20:01:20 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:20 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:20 volumio volumio[837]: error: updateQueue error: null May 28 20:01:20 volumio volumio[837]: info: ------------------------------ 23ms May 28 20:01:21 volumio volumio[837]: info: Preload queue cleared May 28 20:01:21 volumio volumio[837]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::ClearQueue May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::stop May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:21 volumio volumio[837]: info: CorePlayQueue::clearPlayQueue May 28 20:01:21 volumio volumio[837]: info: CorePlayQueue::saveQueue May 28 20:01:21 volumio volumio[837]: info: CoreCommandRouter::volumioPushQueue May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::addQueueItems May 28 20:01:21 volumio volumio[837]: info: CorePlayQueue::addQueueItems May 28 20:01:21 volumio volumio[837]: info: Preload queue cleared May 28 20:01:21 volumio volumio[837]: info: Adding Item to queue: https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio May 28 20:01:21 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 20:01:21 volumio volumio[837]: info: CoreCommandRouter::volumioPushQueue May 28 20:01:21 volumio volumio[837]: info: CorePlayQueue::saveQueue May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::updateTrackBlock May 28 20:01:21 volumio volumio[837]: info: CorePlayQueue::getTrackBlock May 28 20:01:21 volumio volumio[837]: info: CoreCommandRouter::volumioPlay May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::play index 0 May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::stop May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::play index undefined May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:21 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:21 volumio volumio[837]: info: CoreStateMachine::startPlaybackTimer May 28 20:01:21 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:21 volumio volumio[837]: info: [1779987681226] ControllerWebradio::clearAddPlayTrack May 28 20:01:21 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:01:22 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand add "https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio" May 28 20:01:22 volumio volumio[837]: error: updateQueue error: null May 28 20:01:22 volumio volumio[837]: info: ------------------------------ 2013ms May 28 20:01:22 volumio volumio[837]: info: sendMpdCommand stop took 899 milliseconds May 28 20:01:22 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand clear May 28 20:01:22 volumio volumio[837]: info: May 28 20:01:22 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:22 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:22 volumio volumio[837]: info: May 28 20:01:22 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:22 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:22 volumio volumio[837]: info: sendMpdCommand add "https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio" took 10 milliseconds May 28 20:01:22 volumio volumio[837]: info: sendMpdCommand clear took 6 milliseconds May 28 20:01:22 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 20:01:22 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand play May 28 20:01:22 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand load "https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio" May 28 20:01:22 volumio volumio[837]: info: May 28 20:01:22 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:22 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:22 volumio volumio[837]: error: updateQueue error: null May 28 20:01:22 volumio volumio[837]: error: updateQueue error: null May 28 20:01:22 volumio volumio[837]: info: ------------------------------ 17ms May 28 20:01:22 volumio volumio[837]: info: ------------------------------ 16ms May 28 20:01:22 volumio volumio[837]: info: sendMpdCommand play took 11 milliseconds May 28 20:01:22 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand add "https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio" May 28 20:01:22 volumio volumio[837]: error: updateQueue error: null May 28 20:01:22 volumio volumio[837]: info: ------------------------------ 643ms May 28 20:01:22 volumio volumio[837]: info: May 28 20:01:22 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:22 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:22 volumio volumio[837]: info: May 28 20:01:22 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:22 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:22 volumio volumio[837]: info: sendMpdCommand add "https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio" took 16 milliseconds May 28 20:01:22 volumio volumio[837]: info: ------------------------------ 9ms May 28 20:01:22 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 20:01:22 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand play May 28 20:01:22 volumio volumio[837]: info: May 28 20:01:22 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:22 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:22 volumio volumio[837]: info: ------------------------------ 15ms May 28 20:01:22 volumio volumio[837]: info: sendMpdCommand play took 9 milliseconds May 28 20:01:22 volumio volumio[837]: info: ------------------------------ 7ms May 28 20:01:23 volumio volumio[837]: info: May 28 20:01:23 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:23 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:23 volumio volumio[837]: info: May 28 20:01:23 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:23 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:23 volumio volumio[837]: info: May 28 20:01:23 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:23 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:23 volumio volumio[837]: info: May 28 20:01:23 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:23 volumio volumio[837]: info: sendMpdCommand status took 37 milliseconds May 28 20:01:23 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:23 volumio volumio[837]: info: May 28 20:01:23 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:23 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:23 volumio volumio[837]: info: May 28 20:01:23 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:23 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:23 volumio volumio[837]: info: sendMpdCommand status took 52 milliseconds May 28 20:01:23 volumio volumio[837]: info: ------------------------------ 31ms May 28 20:01:23 volumio volumio[837]: info: sendMpdCommand status took 21 milliseconds May 28 20:01:23 volumio volumio[837]: info: sendMpdCommand playlistinfo took 17 milliseconds May 28 20:01:23 volumio volumio[837]: info: ------------------------------ 15ms May 28 20:01:23 volumio volumio[837]: info: sendMpdCommand status took 12 milliseconds May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:23 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:23 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus stop May 28 20:01:23 volumio volumio[837]: info: ------------------------------ 108ms May 28 20:01:23 volumio volumio[837]: info: sendMpdCommand playlistinfo took 29 milliseconds May 28 20:01:23 volumio volumio[837]: info: sendMpdCommand playlistinfo took 28 milliseconds May 28 20:01:23 volumio volumio[837]: info: sendMpdCommand playlistinfo took 22 milliseconds May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:23 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:23 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":272,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:23 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:23 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:23 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:23 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:23 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:23 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:23 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:23 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:23 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:23 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:23 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:24 volumio volumio[837]: info: ------------------------------ 293ms May 28 20:01:24 volumio volumio[837]: info: ------------------------------ 264ms May 28 20:01:24 volumio volumio[837]: info: ------------------------------ 250ms May 28 20:01:28 volumio volumio[837]: info: May 28 20:01:28 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:28 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:28 volumio volumio[837]: info: May 28 20:01:28 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:28 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:28 volumio volumio[837]: info: May 28 20:01:28 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:28 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:28 volumio volumio[837]: info: May 28 20:01:28 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:28 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:28 volumio volumio[837]: info: ------------------------------ 13ms May 28 20:01:28 volumio volumio[837]: info: sendMpdCommand status took 10 milliseconds May 28 20:01:28 volumio volumio[837]: info: ------------------------------ 9ms May 28 20:01:28 volumio volumio[837]: info: sendMpdCommand status took 6 milliseconds May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:28 volumio volumio[837]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:01:28 volumio volumio[837]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:28 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:28 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:28 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5480,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:28 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:28 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:28 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:28 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:28 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:28 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:28 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5480,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:28 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:28 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:28 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:28 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:28 volumio volumio[837]: info: ------------------------------ 127ms May 28 20:01:28 volumio volumio[837]: info: ------------------------------ 125ms May 28 20:01:28 volumio volumio[837]: info: May 28 20:01:28 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:28 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:28 volumio volumio[837]: info: May 28 20:01:28 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:28 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:28 volumio volumio[837]: info: May 28 20:01:28 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:28 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:28 volumio volumio[837]: info: May 28 20:01:28 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:28 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:28 volumio volumio[837]: info: ------------------------------ 16ms May 28 20:01:28 volumio volumio[837]: info: sendMpdCommand status took 12 milliseconds May 28 20:01:28 volumio volumio[837]: info: ------------------------------ 11ms May 28 20:01:28 volumio volumio[837]: info: sendMpdCommand status took 7 milliseconds May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:28 volumio volumio[837]: info: sendMpdCommand playlistinfo took 5 milliseconds May 28 20:01:28 volumio volumio[837]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:28 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:28 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:28 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:28 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6121,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:28 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:28 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:28 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:28 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:28 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:29 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:29 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:29 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:29 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:29 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:29 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:29 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6121,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:29 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:29 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:29 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:29 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:29 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:29 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:29 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:29 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:29 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:29 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:29 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:29 volumio volumio[837]: info: ------------------------------ 135ms May 28 20:01:29 volumio volumio[837]: info: ------------------------------ 130ms May 28 20:01:34 volumio volumio[837]: info: May 28 20:01:34 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:34 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:34 volumio volumio[837]: info: May 28 20:01:34 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:34 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:34 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:34 volumio volumio[837]: info: May 28 20:01:34 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:34 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:34 volumio volumio[837]: info: May 28 20:01:34 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:34 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:34 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:34 volumio volumio[837]: info: ------------------------------ 15ms May 28 20:01:34 volumio volumio[837]: info: sendMpdCommand status took 12 milliseconds May 28 20:01:34 volumio volumio[837]: info: ------------------------------ 10ms May 28 20:01:34 volumio volumio[837]: info: sendMpdCommand status took 6 milliseconds May 28 20:01:34 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:34 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:34 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:34 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:34 volumio volumio[837]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:01:34 volumio volumio[837]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:01:34 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:34 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:34 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:34 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:34 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:34 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11978,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:34 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:34 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:34 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:34 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:34 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:34 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:34 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:34 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:34 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:34 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:34 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:34 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:34 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:34 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:34 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:34 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11978,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:34 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:34 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:34 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:34 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:34 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:34 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:34 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:34 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:34 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:34 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:34 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:34 volumio volumio[837]: info: ------------------------------ 127ms May 28 20:01:34 volumio volumio[837]: info: ------------------------------ 122ms May 28 20:01:35 volumio volumio[837]: info: May 28 20:01:35 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:35 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:35 volumio volumio[837]: info: May 28 20:01:35 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:35 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:35 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:35 volumio volumio[837]: info: May 28 20:01:35 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:35 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:35 volumio volumio[837]: info: May 28 20:01:35 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:35 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:35 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:35 volumio volumio[837]: info: ------------------------------ 15ms May 28 20:01:35 volumio volumio[837]: info: sendMpdCommand status took 12 milliseconds May 28 20:01:35 volumio volumio[837]: info: ------------------------------ 10ms May 28 20:01:35 volumio volumio[837]: info: sendMpdCommand status took 6 milliseconds May 28 20:01:35 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:35 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:35 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:35 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:35 volumio volumio[837]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:01:35 volumio volumio[837]: info: sendMpdCommand playlistinfo took 3 milliseconds May 28 20:01:35 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:35 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:35 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:35 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:35 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:35 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12739,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:35 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:35 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:35 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:35 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:35 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:35 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:35 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:35 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:35 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:35 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:35 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:35 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:35 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:35 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:35 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:35 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12739,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:35 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:35 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:35 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:35 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:35 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:35 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:35 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:35 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:35 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:35 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:35 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:35 volumio volumio[837]: info: ------------------------------ 128ms May 28 20:01:35 volumio volumio[837]: info: ------------------------------ 123ms May 28 20:01:40 volumio volumio[837]: info: May 28 20:01:40 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:40 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:40 volumio volumio[837]: info: May 28 20:01:40 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:40 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:40 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:40 volumio volumio[837]: info: May 28 20:01:40 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:40 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:40 volumio volumio[837]: info: May 28 20:01:40 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:40 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:40 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:40 volumio volumio[837]: info: ------------------------------ 16ms May 28 20:01:40 volumio volumio[837]: info: sendMpdCommand status took 12 milliseconds May 28 20:01:40 volumio volumio[837]: info: ------------------------------ 11ms May 28 20:01:40 volumio volumio[837]: info: sendMpdCommand status took 7 milliseconds May 28 20:01:40 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:40 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:40 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:40 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:40 volumio volumio[837]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:01:40 volumio volumio[837]: info: sendMpdCommand playlistinfo took 3 milliseconds May 28 20:01:40 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:40 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:40 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:40 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:40 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:40 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:40 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:40 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:40 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:40 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:40 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:40 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:40 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:40 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:40 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:40 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:40 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:40 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:40 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:40 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:40 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:40 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:40 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:40 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:40 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:40 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:40 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:40 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:40 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:40 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:40 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:40 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:40 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:40 volumio volumio[837]: info: ------------------------------ 145ms May 28 20:01:40 volumio volumio[837]: info: ------------------------------ 140ms May 28 20:01:41 volumio volumio[837]: info: May 28 20:01:41 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:41 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:41 volumio volumio[837]: info: May 28 20:01:41 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:41 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:41 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:41 volumio volumio[837]: info: ------------------------------ 8ms May 28 20:01:41 volumio volumio[837]: info: sendMpdCommand status took 4 milliseconds May 28 20:01:41 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:41 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:41 volumio volumio[837]: info: May 28 20:01:41 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:01:41 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:01:41 volumio volumio[837]: info: May 28 20:01:41 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:41 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:41 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:41 volumio volumio[837]: info: sendMpdCommand playlistinfo took 9 milliseconds May 28 20:01:41 volumio volumio[837]: info: ------------------------------ 8ms May 28 20:01:41 volumio volumio[837]: info: sendMpdCommand status took 5 milliseconds May 28 20:01:41 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:41 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:41 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:41 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:41 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:41 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:41 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18735,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:41 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:41 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:41 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:41 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:41 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:41 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:41 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:41 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:41 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:41 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:41 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:41 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:41 volumio volumio[837]: info: ------------------------------ 83ms May 28 20:01:41 volumio volumio[837]: info: sendMpdCommand playlistinfo took 63 milliseconds May 28 20:01:41 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:41 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:41 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:41 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:41 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18735,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"155 Kbps","isStreaming":false,"title":"icecast.audio","artist":"Yle Radio Suomi - Hämeenlinna","album":null,"uri":"https://icecast.live.yle.fi/radio/YleHameenlinna/icecast.audio","trackType":"audio"} May 28 20:01:41 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:01:41 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:01:41 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:01:41 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:01:41 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:01:41 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:41 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:41 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:41 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:41 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:41 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:41 volumio volumio[837]: info: ------------------------------ 135ms May 28 20:01:44 volumio volumio[837]: info: CoreCommandRouter::volumioStop May 28 20:01:44 volumio volumio[837]: info: CoreStateMachine::stop May 28 20:01:44 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:01:44 volumio volumio[837]: info: CoreStateMachine::stPlaybackTimer May 28 20:01:44 volumio volumio[837]: info: CoreStateMachine::updateTrackBlock May 28 20:01:44 volumio volumio[837]: info: CorePlayQueue::getTrackBlock May 28 20:01:44 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:44 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:44 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:44 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:44 volumio volumio[837]: info: CoreStateMachine::serviceStop May 28 20:01:44 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:44 volumio volumio[837]: info: CoreCommandRouter::serviceStop May 28 20:01:44 volumio volumio[837]: info: [1779987704520] ControllerWebradio::stop May 28 20:01:44 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:01:44 volumio volumio[837]: info: sendMpdCommand stop took 37 milliseconds May 28 20:01:44 volumio volumio[837]: info: May 28 20:01:44 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:44 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:44 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:44 volumio volumio[837]: info: May 28 20:01:44 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:01:44 volumio volumio[837]: info: ControllerMpd::getState May 28 20:01:44 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:01:44 volumio volumio[837]: info: sendMpdCommand status took 5 milliseconds May 28 20:01:44 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:44 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:44 volumio volumio[837]: info: sendMpdCommand status took 7 milliseconds May 28 20:01:44 volumio volumio[837]: info: sendMpdCommand playlistinfo took 3 milliseconds May 28 20:01:44 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:01:44 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:01:44 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:44 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:44 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:44 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:44 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:44 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:01:44 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:44 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:44 volumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 28 20:01:44 volumio volumio[837]: info: ------------------------------ 46ms May 28 20:01:44 volumio volumio[837]: info: sendMpdCommand playlistinfo took 33 milliseconds May 28 20:01:44 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:01:44 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:01:44 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:01:44 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:01:44 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:44 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:01:44 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:01:44 volumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 28 20:01:44 volumio volumio[837]: info: ------------------------------ 74ms May 28 20:01:45 volumio dbus-daemon[511]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30028ms) May 28 20:01:58 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 20:02:00 volumio volumio[837]: info: Preload queue cleared May 28 20:02:05 volumio volumio[837]: info: Preload queue cleared May 28 20:02:05 volumio volumio[837]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::ClearQueue May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::stop May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:02:05 volumio volumio[837]: info: CorePlayQueue::clearPlayQueue May 28 20:02:05 volumio volumio[837]: info: CorePlayQueue::saveQueue May 28 20:02:05 volumio volumio[837]: info: CoreCommandRouter::volumioPushQueue May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::addQueueItems May 28 20:02:05 volumio volumio[837]: info: CorePlayQueue::addQueueItems May 28 20:02:05 volumio volumio[837]: info: Preload queue cleared May 28 20:02:05 volumio volumio[837]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 May 28 20:02:05 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 20:02:05 volumio volumio[837]: info: CoreCommandRouter::volumioPushQueue May 28 20:02:05 volumio volumio[837]: info: CorePlayQueue::saveQueue May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::updateTrackBlock May 28 20:02:05 volumio volumio[837]: info: CorePlayQueue::getTrackBlock May 28 20:02:05 volumio volumio[837]: info: CoreCommandRouter::volumioPlay May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::play index 0 May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::stop May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::play index undefined May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:02:05 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:05 volumio volumio[837]: info: CoreStateMachine::startPlaybackTimer May 28 20:02:05 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:05 volumio volumio[837]: info: [1779987725551] ControllerWebradio::clearAddPlayTrack May 28 20:02:05 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:02:05 volumio volumio[837]: info: sendMpdCommand stop took 6 milliseconds May 28 20:02:05 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand clear May 28 20:02:05 volumio volumio[837]: info: May 28 20:02:05 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:05 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:05 volumio volumio[837]: info: sendMpdCommand clear took 5 milliseconds May 28 20:02:05 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" May 28 20:02:05 volumio volumio[837]: info: May 28 20:02:05 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:05 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:05 volumio volumio[837]: error: updateQueue error: null May 28 20:02:05 volumio volumio[837]: info: ------------------------------ 11ms May 28 20:02:06 volumio volumio[837]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 816 milliseconds May 28 20:02:06 volumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 20:02:06 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand play May 28 20:02:06 volumio volumio[837]: info: May 28 20:02:06 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:06 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:06 volumio volumio[837]: info: ------------------------------ 821ms May 28 20:02:06 volumio volumio[837]: info: sendMpdCommand play took 5 milliseconds May 28 20:02:06 volumio volumio[837]: info: May 28 20:02:06 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:06 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:06 volumio volumio[837]: info: ------------------------------ 8ms May 28 20:02:06 volumio volumio[837]: info: ------------------------------ 4ms May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand status took 9 milliseconds May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand status took 6 milliseconds May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand status took 12 milliseconds May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand playlistinfo took 8 milliseconds May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand playlistinfo took 8 milliseconds May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand status took 5 milliseconds May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: 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 - 6265/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} May 28 20:02:08 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus stop May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: 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 - 6265/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} May 28 20:02:08 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:02:08 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 66ms May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 91ms May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:08 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand playlistinfo took 80 milliseconds May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand playlistinfo took 78 milliseconds May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: 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 - 6265/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} May 28 20:02:08 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:02:08 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: 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 - 6265/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} May 28 20:02:08 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:02:08 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 203ms May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 196ms May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:08 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:08 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 147ms May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand status took 143 milliseconds May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 18ms May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand status took 13 milliseconds May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:08 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:08 volumio volumio[837]: info: May 28 20:02:08 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 24ms May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand status took 19 milliseconds May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand playlistinfo took 13 milliseconds May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand playlistinfo took 12 milliseconds May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 11ms May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand status took 8 milliseconds May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"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"} May 28 20:02:08 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:02:08 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 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"} May 28 20:02:08 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:02:08 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 282ms May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 152ms May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand playlistinfo took 117 milliseconds May 28 20:02:08 volumio volumio[837]: info: sendMpdCommand playlistinfo took 114 milliseconds May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:08 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 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"} May 28 20:02:08 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:02:08 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:08 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:08 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 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"} May 28 20:02:08 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:02:08 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:08 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 277ms May 28 20:02:08 volumio volumio[837]: info: ------------------------------ 263ms May 28 20:02:11 volumio volumio[837]: info: May 28 20:02:11 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:11 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:11 volumio volumio[837]: info: May 28 20:02:11 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:11 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:11 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:11 volumio volumio[837]: info: May 28 20:02:11 volumio volumio[837]: ---------------------------- MPD announces system playlist update May 28 20:02:11 volumio volumio[837]: info: Ignoring MPD Status Update May 28 20:02:11 volumio volumio[837]: info: May 28 20:02:11 volumio volumio[837]: ---------------------------- MPD announces state update: player May 28 20:02:11 volumio volumio[837]: info: ControllerMpd::getState May 28 20:02:11 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status May 28 20:02:11 volumio volumio[837]: info: ------------------------------ 17ms May 28 20:02:11 volumio volumio[837]: info: sendMpdCommand status took 12 milliseconds May 28 20:02:11 volumio volumio[837]: info: ------------------------------ 10ms May 28 20:02:11 volumio volumio[837]: info: sendMpdCommand status took 7 milliseconds May 28 20:02:11 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:11 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:11 volumio volumio[837]: verbose: ControllerMpd::parseState May 28 20:02:11 volumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:02:11 volumio volumio[837]: info: sendMpdCommand playlistinfo took 3 milliseconds May 28 20:02:11 volumio volumio[837]: info: sendMpdCommand playlistinfo took 3 milliseconds May 28 20:02:11 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:11 volumio volumio[837]: verbose: ControllerMpd::parseTrackInfo May 28 20:02:11 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:11 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:11 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:11 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} May 28 20:02:11 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:11 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:11 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:02:11 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:02:11 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:02:11 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:11 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:02:11 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:11 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:11 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:11 volumio volumio[837]: info: ControllerMpd::pushState May 28 20:02:11 volumio volumio[837]: info: CoreCommandRouter::servicePushState May 28 20:02:11 volumio volumio[837]: info: CorePlayQueue::getTrack 0 May 28 20:02:11 volumio volumio[837]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} May 28 20:02:11 volumio volumio[837]: verbose: CURRENT POSITION 0 May 28 20:02:11 volumio volumio[837]: info: CoreStateMachine::syncState stateService play May 28 20:02:11 volumio volumio[837]: info: CoreStateMachine::syncState currentStatus play May 28 20:02:11 volumio volumio[837]: info: Received an update from plugin. extracting info from payload May 28 20:02:11 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 20:02:11 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:11 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:11 volumio volumio[837]: info: CoreStateMachine::pushState May 28 20:02:11 volumio volumio[837]: info: CoreCommandRouter::volumioPushState May 28 20:02:11 volumio volumio[837]: info: ------------------------------ 128ms May 28 20:02:11 volumio volumio[837]: info: ------------------------------ 123ms May 28 20:02:11 volumio volumio[837]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 20:02:11 volumio volumio[837]: TypeError: Cannot read property 'slice' of null May 28 20:02:11 volumio volumio[837]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) May 28 20:02:11 volumio volumio[837]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) May 28 20:02:11 volumio volumio[837]: at Object.onceWrapper (events.js:422:26) May 28 20:02:11 volumio volumio[837]: at ClientRequest.emit (events.js:315:20) May 28 20:02:11 volumio volumio[837]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) May 28 20:02:11 volumio volumio[837]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) May 28 20:02:11 volumio volumio[837]: at TLSSocket.socketOnData (_http_client.js:509:22) May 28 20:02:11 volumio volumio[837]: at TLSSocket.emit (events.js:315:20) May 28 20:02:11 volumio volumio[837]: at addChunk (internal/streams/readable.js:309:12) May 28 20:02:11 volumio volumio[837]: at readableAddChunk (internal/streams/readable.js:284:9) May 28 20:02:11 volumio volumio[837]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) May 28 20:02:11 volumio volumio[837]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) May 28 20:02:11 volumio volumio[837]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 20:02:12 volumio sudo[2666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-28 20:01 May 28 20:02:12 volumio sudo[2666]: 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"