-- Logs begin at Thu 2019-02-14 21:12:00 AEDT, end at Sun 2026-01-11 00:53:19 AEDT. -- Jan 11 00:52:15 volumio volumio[956]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 11 00:52:15 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:15 volumio volumio[956]: info: Restoring Previous Volume level: 100 false true Jan 11 00:52:15 volumio volumio[956]: info: VolumeController::SetAlsaVolume100 Jan 11 00:52:15 volumio volumio[956]: info: Enable softmixer device for audio device number 2 Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::volumioStop Jan 11 00:52:15 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:15 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:15 volumio volumio[956]: info: Enable softmixer device for audio device undefined Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 11 00:52:15 volumio volumio[956]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 11 00:52:15 volumio volumio[956]: info: Disabling external Volume Control Jan 11 00:52:15 volumio volumio[956]: info: Output device has changed, restarting MPD Jan 11 00:52:15 volumio volumio[956]: info: Output device has changed, restarting Shairport Sync Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:52:15 volumio sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 00:52:15 volumio sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:15 volumio sudo[1622]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:15 volumio sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 00:52:15 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:15 volumio systemd[1]: Stopping Music Player Daemon... Jan 11 00:52:15 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 00:52:15 volumio volumio[956]: info: Volume configurations have been set Jan 11 00:52:15 volumio systemd[1]: mpd.service: Succeeded. Jan 11 00:52:15 volumio systemd[1]: Stopped Music Player Daemon. Jan 11 00:52:15 volumio systemd[1]: Starting Music Player Daemon... Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 11 00:52:15 volumio volumio[956]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 11 00:52:15 volumio volumio[956]: info: Disabling external Volume Control Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 00:52:15 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:15 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:15 volumio volumio[956]: info: MPD Permissions set Jan 11 00:52:15 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:15 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:15 volumio sudo[1631]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 00:52:15 volumio sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:15 volumio volumio[956]: info: Software Volume ALSA configuration written Jan 11 00:52:15 volumio volumio[956]: info: Preparing to generate the ALSA configuration file Jan 11 00:52:15 volumio sudo[1631]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:52:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 11 00:52:16 volumio volumio[956]: info: Reading ALSA contributions from plugins. Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 00:52:16 volumio volumio[956]: info: Getting Alsa Cards List without I2S DAC Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 00:52:16 volumio volumio[956]: info: VolumeController:: Volume=100 Mute =false Jan 11 00:52:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:16 volumio volumio[956]: info: VolumeController::SetAlsaVolume0 Jan 11 00:52:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:16 volumio volumio[956]: info: Starting Shairport Sync Jan 11 00:52:16 volumio volumio[956]: info: Asound.conf file written Jan 11 00:52:16 volumio sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 00:52:16 volumio sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:16 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 11 00:52:16 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 11 00:52:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 11 00:52:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 11 00:52:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 11 00:52:16 volumio sudo[1657]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:17 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:17 volumio volumio[956]: No state is present for card b1 Jan 11 00:52:17 volumio volumio[956]: /usr/share/alsa/init/bcm2835:14: control element not found Jan 11 00:52:17 volumio volumio[956]: No state is present for card b1 Jan 11 00:52:17 volumio volumio[956]: No state is present for card Headphones Jan 11 00:52:17 volumio volumio[956]: /usr/share/alsa/init/bcm2835:20: control element not found Jan 11 00:52:17 volumio volumio[956]: No state is present for card Headphones Jan 11 00:52:17 volumio volumio[956]: No state is present for card sndrpihifiberry Jan 11 00:52:17 volumio volumio[956]: Found hardware: "HifiberryDacp" "" "" "" "" Jan 11 00:52:17 volumio volumio[956]: Hardware is initialized using a generic method Jan 11 00:52:17 volumio volumio[956]: No state is present for card sndrpihifiberry Jan 11 00:52:17 volumio volumio[956]: info: Output device has changed, restarting MPD Jan 11 00:52:18 volumio volumio[956]: info: Output device has changed, restarting Shairport Sync Jan 11 00:52:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:52:18 volumio sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 00:52:18 volumio sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:18 volumio sudo[1706]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:18 volumio sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 00:52:18 volumio sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 11 00:52:18 volumio systemd[1]: mpd.service: Succeeded. Jan 11 00:52:18 volumio systemd[1]: Stopped Music Player Daemon. Jan 11 00:52:18 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 00:52:18 volumio systemd[1]: Starting Music Player Daemon... Jan 11 00:52:18 volumio sudo[1714]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 00:52:18 volumio sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:18 volumio sudo[1714]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:19 volumio volumio[956]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jan 11 00:52:19 volumio volumio[956]: No state is present for card b1 Jan 11 00:52:19 volumio volumio[956]: /usr/share/alsa/init/bcm2835:14: control element not found Jan 11 00:52:19 volumio volumio[956]: No state is present for card b1 Jan 11 00:52:19 volumio volumio[956]: No state is present for card Headphones Jan 11 00:52:19 volumio volumio[956]: /usr/share/alsa/init/bcm2835:20: control element not found Jan 11 00:52:19 volumio volumio[956]: No state is present for card Headphones Jan 11 00:52:19 volumio volumio[956]: No state is present for card sndrpihifiberry Jan 11 00:52:19 volumio volumio[956]: Found hardware: "HifiberryDacp" "" "" "" "" Jan 11 00:52:19 volumio volumio[956]: Hardware is initialized using a generic method Jan 11 00:52:19 volumio volumio[956]: No state is present for card sndrpihifiberry Jan 11 00:52:19 volumio volumio[956]: info: Output device has changed, restarting MPD Jan 11 00:52:19 volumio volumio[956]: info: Output device has changed, restarting Shairport Sync Jan 11 00:52:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:52:19 volumio sudo[1736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 00:52:19 volumio sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:19 volumio sudo[1736]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:19 volumio sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 00:52:19 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 11 00:52:19 volumio systemd[1]: mpd.service: Succeeded. Jan 11 00:52:19 volumio systemd[1]: Stopped Music Player Daemon. Jan 11 00:52:19 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 00:52:19 volumio systemd[1]: Starting Music Player Daemon... Jan 11 00:52:19 volumio volumio[956]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 11 00:52:20 volumio volumio[956]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 11 00:52:20 volumio volumio[956]: info: Disabling external Volume Control Jan 11 00:52:20 volumio volumio[956]: info: VolumeController::SetAlsaVolume100 Jan 11 00:52:20 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:20 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:20 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:20 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:20 volumio sudo[1744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 00:52:20 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:20 volumio volumio[956]: info: MPD Permissions set Jan 11 00:52:20 volumio volumio[956]: info: Shairport-Sync Started Jan 11 00:52:20 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 00:52:20 volumio volumio[956]: info: Starting Shairport Sync Jan 11 00:52:20 volumio volumio[956]: info: Starting Shairport Sync Jan 11 00:52:20 volumio sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 00:52:20 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:20 volumio sudo[1768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 00:52:20 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 11 00:52:20 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 11 00:52:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 11 00:52:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 11 00:52:20 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:20 volumio volumio[956]: info: Shairport-Sync Started Jan 11 00:52:20 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:20 volumio volumio[956]: info: Shairport-Sync Started Jan 11 00:52:20 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 11 00:52:20 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jan 11 00:52:21 volumio sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 00:52:21 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:21 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 11 00:52:21 volumio systemd[1]: mpd.service: Succeeded. Jan 11 00:52:21 volumio systemd[1]: Stopped Music Player Daemon. Jan 11 00:52:21 volumio systemd[1]: Starting Music Player Daemon... Jan 11 00:52:21 volumio sudo[1777]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 00:52:21 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:22 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:24 volumio mpd[1779]: Jan 11 00:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 00:52:24 volumio systemd[1]: Started Music Player Daemon. Jan 11 00:52:24 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:24 volumio sudo[1708]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:24 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:24 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:24 volumio volumio[956]: error: MPD error: The expression evaluated to a falsy value: Jan 11 00:52:24 volumio volumio[956]: assert.ok(self.idling) Jan 11 00:52:24 volumio volumio[956]: error: The expression evaluated to a falsy value: Jan 11 00:52:24 volumio volumio[956]: assert.ok(self.idling) Jan 11 00:52:24 volumio volumio[956]: error: MPD error: The expression evaluated to a falsy value: Jan 11 00:52:24 volumio volumio[956]: assert.ok(self.idling) Jan 11 00:52:24 volumio volumio[956]: error: The expression evaluated to a falsy value: Jan 11 00:52:24 volumio volumio[956]: assert.ok(self.idling) Jan 11 00:52:24 volumio volumio[956]: error: MPD error: The expression evaluated to a falsy value: Jan 11 00:52:24 volumio volumio[956]: assert.ok(self.idling) Jan 11 00:52:24 volumio volumio[956]: error: The expression evaluated to a falsy value: Jan 11 00:52:24 volumio volumio[956]: assert.ok(self.idling) Jan 11 00:52:24 volumio volumio[956]: error: updateQueue error: null Jan 11 00:52:25 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Jan 11 00:52:25 volumio volumio[956]: info: CoreStateMachine::getQueue Jan 11 00:52:25 volumio volumio[956]: info: CorePlayQueue::getQueue Jan 11 00:52:27 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 11 00:52:27 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:27 volumio sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 00:52:27 volumio sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:27 volumio sudo[1788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 00:52:27 volumio sudo[1788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:27 volumio sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 00:52:27 volumio sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:27 volumio sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 00:52:27 volumio sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 00:52:27 volumio systemd[1]: Stopping MPD Monitor Service... Jan 11 00:52:27 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 11 00:52:27 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 11 00:52:27 volumio systemd[1]: Stopped MPD Monitor Service. Jan 11 00:52:28 volumio systemd[1]: Started MPD Monitor Service. Jan 11 00:52:28 volumio sudo[1786]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:28 volumio sudo[1791]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:28 volumio sudo[1794]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:28 volumio mpd_monitor.sh[1797]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 00:52:28 volumio sudo[1788]: pam_unix(sudo:session): session closed for user root Jan 11 00:52:28 volumio volumio[956]: info: Successfully started MPD Monitor Jan 11 00:52:28 volumio volumio[956]: info: Successfully started MPD Monitor Jan 11 00:52:28 volumio volumio[956]: info: Successfully started MPD Monitor Jan 11 00:52:28 volumio volumio[956]: info: Successfully started MPD Monitor Jan 11 00:52:30 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 11 00:52:30 volumio volumio[956]: info: READ Jan 11 00:52:30 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:35 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 11 00:52:35 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:38 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:38 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:38 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:52:38 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:38 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:52:38 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:52:38 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:38 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99253474 Jan 11 00:52:38 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:52:38 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:38 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:52:38 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:52:38 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:38 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:38 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:52:38 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:38 volumio volumio[956]: info: [1768053158241] ControllerWebradio::clearAddPlayTrack Jan 11 00:52:38 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:52:38 volumio volumio[956]: info: sendMpdCommand stop took 10 milliseconds Jan 11 00:52:38 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:52:38 volumio volumio[956]: info: Jan 11 00:52:38 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:38 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:38 volumio volumio[956]: info: sendMpdCommand clear took 6 milliseconds Jan 11 00:52:38 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99253474" Jan 11 00:52:38 volumio volumio[956]: info: Jan 11 00:52:38 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:38 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:38 volumio volumio[956]: info: Jan 11 00:52:38 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:38 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:38 volumio volumio[956]: info: Jan 11 00:52:38 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:38 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:38 volumio volumio[956]: error: updateQueue error: null Jan 11 00:52:38 volumio volumio[956]: info: ------------------------------ 25ms Jan 11 00:52:39 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99253474" took 1373 milliseconds Jan 11 00:52:39 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:52:39 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:52:39 volumio volumio[956]: info: Jan 11 00:52:39 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:39 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:39 volumio volumio[956]: info: ------------------------------ 1382ms Jan 11 00:52:39 volumio volumio[956]: info: ------------------------------ 1379ms Jan 11 00:52:39 volumio volumio[956]: info: ------------------------------ 1376ms Jan 11 00:52:39 volumio volumio[956]: info: Jan 11 00:52:39 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:39 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:39 volumio volumio[956]: info: Jan 11 00:52:39 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:39 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:39 volumio volumio[956]: info: Jan 11 00:52:39 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:39 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:39 volumio volumio[956]: info: sendMpdCommand play took 29 milliseconds Jan 11 00:52:39 volumio volumio[956]: info: ------------------------------ 28ms Jan 11 00:52:39 volumio volumio[956]: info: ------------------------------ 20ms Jan 11 00:52:39 volumio volumio[956]: info: ------------------------------ 17ms Jan 11 00:52:39 volumio volumio[956]: info: ------------------------------ 8ms Jan 11 00:52:40 volumio volumio[956]: info: Jan 11 00:52:40 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:40 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:40 volumio volumio[956]: info: Jan 11 00:52:40 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:40 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:40 volumio volumio[956]: info: Jan 11 00:52:40 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:40 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand status took 24 milliseconds Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand status took 24 milliseconds Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand status took 22 milliseconds Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:40 volumio volumio[956]: info: Jan 11 00:52:40 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:40 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand clearerror took 38 milliseconds Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand clearerror took 35 milliseconds Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand clearerror took 33 milliseconds Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand status took 8 milliseconds Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:40 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:40 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"wwe24x7","artist":"wwe24x7","album":null,"uri":"http://listen.shoutcast.com/wwe24x7","trackType":""} Jan 11 00:52:40 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: No code Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:40 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"wwe24x7","artist":"wwe24x7","album":null,"uri":"http://listen.shoutcast.com/wwe24x7","trackType":""} Jan 11 00:52:40 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: No code Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:40 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"wwe24x7","artist":"wwe24x7","album":null,"uri":"http://listen.shoutcast.com/wwe24x7","trackType":""} Jan 11 00:52:40 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: No code Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: ------------------------------ 256ms Jan 11 00:52:40 volumio volumio[956]: info: ------------------------------ 258ms Jan 11 00:52:40 volumio volumio[956]: info: ------------------------------ 257ms Jan 11 00:52:40 volumio volumio[956]: info: sendMpdCommand playlistinfo took 214 milliseconds Jan 11 00:52:40 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:40 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:40 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"wwe24x7","artist":"wwe24x7","album":null,"uri":"http://listen.shoutcast.com/wwe24x7","trackType":""} Jan 11 00:52:40 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: No code Jan 11 00:52:40 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:40 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:40 volumio volumio[956]: info: ------------------------------ 288ms Jan 11 00:52:42 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:42 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:42 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:52:42 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:42 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:52:42 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:52:42 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:42 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99217572 Jan 11 00:52:42 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:52:42 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:42 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:52:42 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:52:42 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:42 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:42 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:52:42 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:43 volumio volumio[956]: info: [1768053163000] ControllerWebradio::clearAddPlayTrack Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand stop took 5 milliseconds Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:43 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand clear took 3 milliseconds Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99217572" Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:43 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:43 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:43 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:43 volumio volumio[956]: error: updateQueue error: null Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 15ms Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99217572" took 245 milliseconds Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:43 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 251ms Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 249ms Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 247ms Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand play took 7 milliseconds Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:43 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:43 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:43 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 20ms Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 12ms Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 9ms Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 6ms Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:43 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:43 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:43 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand status took 20 milliseconds Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand status took 18 milliseconds Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:43 volumio volumio[956]: info: Jan 11 00:52:43 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:43 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand status took 41 milliseconds Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand clearerror took 32 milliseconds Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand clearerror took 29 milliseconds Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand status took 15 milliseconds Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:43 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:43 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dancingqueen","artist":"d a n c i n g q u e e n","album":null,"uri":"http://listen.shoutcast.com/dancingqueen","trackType":""} Jan 11 00:52:43 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: No code Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:43 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dancingqueen","artist":"d a n c i n g q u e e n","album":null,"uri":"http://listen.shoutcast.com/dancingqueen","trackType":""} Jan 11 00:52:43 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: No code Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 161ms Jan 11 00:52:43 volumio volumio[956]: info: ------------------------------ 160ms Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand clearerror took 140 milliseconds Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand playlistinfo took 134 milliseconds Jan 11 00:52:43 volumio volumio[956]: info: sendMpdCommand playlistinfo took 132 milliseconds Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:43 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:43 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:43 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dancingqueen","artist":"d a n c i n g q u e e n","album":null,"uri":"http://listen.shoutcast.com/dancingqueen","trackType":""} Jan 11 00:52:43 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: No code Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:43 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dancingqueen","artist":"d a n c i n g q u e e n","album":null,"uri":"http://listen.shoutcast.com/dancingqueen","trackType":""} Jan 11 00:52:43 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:43 volumio volumio[956]: info: No code Jan 11 00:52:43 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:43 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:44 volumio volumio[956]: info: ------------------------------ 284ms Jan 11 00:52:44 volumio volumio[956]: info: ------------------------------ 260ms Jan 11 00:52:45 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:45 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:45 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:52:45 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:45 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:52:45 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:52:45 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:45 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99211556 Jan 11 00:52:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:52:45 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:45 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:52:45 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:52:45 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:45 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:52:45 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:45 volumio volumio[956]: info: [1768053165606] ControllerWebradio::clearAddPlayTrack Jan 11 00:52:45 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:52:45 volumio volumio[956]: info: sendMpdCommand stop took 5 milliseconds Jan 11 00:52:45 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:52:45 volumio volumio[956]: info: Jan 11 00:52:45 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:45 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:45 volumio volumio[956]: info: sendMpdCommand clear took 3 milliseconds Jan 11 00:52:45 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99211556" Jan 11 00:52:45 volumio volumio[956]: info: Jan 11 00:52:45 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:45 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:45 volumio volumio[956]: info: Jan 11 00:52:45 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:45 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:45 volumio volumio[956]: info: Jan 11 00:52:45 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:45 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:45 volumio volumio[956]: error: updateQueue error: null Jan 11 00:52:45 volumio volumio[956]: info: ------------------------------ 14ms Jan 11 00:52:45 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99211556" took 244 milliseconds Jan 11 00:52:45 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:52:45 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:52:45 volumio volumio[956]: info: Jan 11 00:52:45 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:45 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:45 volumio volumio[956]: info: ------------------------------ 250ms Jan 11 00:52:45 volumio volumio[956]: info: ------------------------------ 248ms Jan 11 00:52:45 volumio volumio[956]: info: ------------------------------ 246ms Jan 11 00:52:45 volumio volumio[956]: info: sendMpdCommand play took 6 milliseconds Jan 11 00:52:45 volumio volumio[956]: info: Jan 11 00:52:45 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:45 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:45 volumio volumio[956]: info: Jan 11 00:52:45 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:45 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:45 volumio volumio[956]: info: Jan 11 00:52:45 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:45 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:45 volumio volumio[956]: info: ------------------------------ 15ms Jan 11 00:52:45 volumio volumio[956]: info: ------------------------------ 10ms Jan 11 00:52:45 volumio volumio[956]: info: ------------------------------ 8ms Jan 11 00:52:45 volumio volumio[956]: info: ------------------------------ 6ms Jan 11 00:52:46 volumio volumio[956]: info: Jan 11 00:52:46 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:46 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:46 volumio volumio[956]: info: Jan 11 00:52:46 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:46 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:46 volumio volumio[956]: info: Jan 11 00:52:46 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:46 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand status took 19 milliseconds Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand status took 18 milliseconds Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:46 volumio volumio[956]: info: Jan 11 00:52:46 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:46 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand status took 46 milliseconds Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand clearerror took 35 milliseconds Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand clearerror took 33 milliseconds Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand status took 15 milliseconds Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:46 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:46 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazzvibe","artist":"JAZZ Vibe","album":null,"uri":"http://listen.shoutcast.com/jazzvibe","trackType":""} Jan 11 00:52:46 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: No code Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:46 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazzvibe","artist":"JAZZ Vibe","album":null,"uri":"http://listen.shoutcast.com/jazzvibe","trackType":""} Jan 11 00:52:46 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: No code Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: ------------------------------ 169ms Jan 11 00:52:46 volumio volumio[956]: info: ------------------------------ 167ms Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand clearerror took 136 milliseconds Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand playlistinfo took 131 milliseconds Jan 11 00:52:46 volumio volumio[956]: info: sendMpdCommand playlistinfo took 129 milliseconds Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:46 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:46 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:46 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazzvibe","artist":"JAZZ Vibe","album":null,"uri":"http://listen.shoutcast.com/jazzvibe","trackType":""} Jan 11 00:52:46 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: No code Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:46 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazzvibe","artist":"JAZZ Vibe","album":null,"uri":"http://listen.shoutcast.com/jazzvibe","trackType":""} Jan 11 00:52:46 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: No code Jan 11 00:52:46 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:46 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:46 volumio volumio[956]: info: ------------------------------ 293ms Jan 11 00:52:46 volumio volumio[956]: info: ------------------------------ 261ms Jan 11 00:52:47 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:47 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:47 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:52:47 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:47 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:52:47 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:52:47 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:47 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=534083 Jan 11 00:52:47 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:52:47 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:47 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:52:47 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:52:47 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:47 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:52:47 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:47 volumio volumio[956]: info: [1768053167583] ControllerWebradio::clearAddPlayTrack Jan 11 00:52:47 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:52:47 volumio volumio[956]: info: sendMpdCommand stop took 5 milliseconds Jan 11 00:52:47 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:52:47 volumio volumio[956]: info: Jan 11 00:52:47 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:47 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:47 volumio volumio[956]: info: sendMpdCommand clear took 7 milliseconds Jan 11 00:52:47 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=534083" Jan 11 00:52:47 volumio volumio[956]: info: Jan 11 00:52:47 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:47 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:47 volumio volumio[956]: info: Jan 11 00:52:47 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:47 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:47 volumio volumio[956]: info: Jan 11 00:52:47 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:47 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:47 volumio volumio[956]: error: updateQueue error: null Jan 11 00:52:47 volumio volumio[956]: info: ------------------------------ 18ms Jan 11 00:52:47 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=534083" took 246 milliseconds Jan 11 00:52:47 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:52:47 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:52:47 volumio volumio[956]: info: Jan 11 00:52:47 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:47 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:47 volumio volumio[956]: info: ------------------------------ 251ms Jan 11 00:52:47 volumio volumio[956]: info: ------------------------------ 250ms Jan 11 00:52:47 volumio volumio[956]: info: ------------------------------ 247ms Jan 11 00:52:47 volumio volumio[956]: info: sendMpdCommand play took 7 milliseconds Jan 11 00:52:47 volumio volumio[956]: info: Jan 11 00:52:47 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:47 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:47 volumio volumio[956]: info: Jan 11 00:52:47 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:47 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:47 volumio volumio[956]: info: Jan 11 00:52:47 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:47 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:47 volumio volumio[956]: info: ------------------------------ 16ms Jan 11 00:52:47 volumio volumio[956]: info: ------------------------------ 10ms Jan 11 00:52:47 volumio volumio[956]: info: ------------------------------ 8ms Jan 11 00:52:47 volumio volumio[956]: info: ------------------------------ 6ms Jan 11 00:52:49 volumio volumio[956]: info: Jan 11 00:52:49 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:49 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:49 volumio volumio[956]: info: Jan 11 00:52:49 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:49 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:49 volumio volumio[956]: info: Jan 11 00:52:49 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:49 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand status took 19 milliseconds Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand status took 17 milliseconds Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:49 volumio volumio[956]: info: Jan 11 00:52:49 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:49 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand status took 39 milliseconds Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand clearerror took 29 milliseconds Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand clearerror took 27 milliseconds Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand status took 14 milliseconds Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:49 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:49 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lovejazz","artist":"Love Jazz Florida","album":null,"uri":"http://listen.streamonomy.com/lovejazz","trackType":""} Jan 11 00:52:49 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: No code Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:49 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lovejazz","artist":"Love Jazz Florida","album":null,"uri":"http://listen.streamonomy.com/lovejazz","trackType":""} Jan 11 00:52:49 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: No code Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: ------------------------------ 157ms Jan 11 00:52:49 volumio volumio[956]: info: ------------------------------ 155ms Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand clearerror took 135 milliseconds Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand playlistinfo took 130 milliseconds Jan 11 00:52:49 volumio volumio[956]: info: sendMpdCommand playlistinfo took 129 milliseconds Jan 11 00:52:49 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:49 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:49 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lovejazz","artist":"Love Jazz Florida","album":null,"uri":"http://listen.streamonomy.com/lovejazz","trackType":""} Jan 11 00:52:49 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: No code Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:49 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lovejazz","artist":"Love Jazz Florida","album":null,"uri":"http://listen.streamonomy.com/lovejazz","trackType":""} Jan 11 00:52:49 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: No code Jan 11 00:52:49 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:49 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:49 volumio volumio[956]: info: ------------------------------ 278ms Jan 11 00:52:49 volumio volumio[956]: info: ------------------------------ 252ms Jan 11 00:52:50 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:50 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:50 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:52:50 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:50 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:52:50 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:52:50 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:50 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99325244 Jan 11 00:52:50 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:52:50 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:50 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:52:50 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:52:50 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:50 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:52:50 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:50 volumio volumio[956]: info: [1768053170315] ControllerWebradio::clearAddPlayTrack Jan 11 00:52:50 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:52:50 volumio volumio[956]: info: sendMpdCommand stop took 5 milliseconds Jan 11 00:52:50 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:52:50 volumio volumio[956]: info: Jan 11 00:52:50 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:50 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:50 volumio volumio[956]: info: sendMpdCommand clear took 3 milliseconds Jan 11 00:52:50 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99325244" Jan 11 00:52:50 volumio volumio[956]: info: Jan 11 00:52:50 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:50 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:50 volumio volumio[956]: info: Jan 11 00:52:50 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:50 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:50 volumio volumio[956]: info: Jan 11 00:52:50 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:50 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:50 volumio volumio[956]: error: updateQueue error: null Jan 11 00:52:50 volumio volumio[956]: info: ------------------------------ 14ms Jan 11 00:52:50 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99325244" took 244 milliseconds Jan 11 00:52:50 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:52:50 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:52:50 volumio volumio[956]: info: Jan 11 00:52:50 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:50 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:50 volumio volumio[956]: info: ------------------------------ 250ms Jan 11 00:52:50 volumio volumio[956]: info: ------------------------------ 248ms Jan 11 00:52:50 volumio volumio[956]: info: ------------------------------ 246ms Jan 11 00:52:50 volumio volumio[956]: info: sendMpdCommand play took 7 milliseconds Jan 11 00:52:50 volumio volumio[956]: info: Jan 11 00:52:50 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:50 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:50 volumio volumio[956]: info: Jan 11 00:52:50 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:50 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:50 volumio volumio[956]: info: Jan 11 00:52:50 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:50 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:50 volumio volumio[956]: info: ------------------------------ 15ms Jan 11 00:52:50 volumio volumio[956]: info: ------------------------------ 10ms Jan 11 00:52:50 volumio volumio[956]: info: ------------------------------ 8ms Jan 11 00:52:50 volumio volumio[956]: info: ------------------------------ 6ms Jan 11 00:52:51 volumio volumio[956]: info: Jan 11 00:52:51 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:51 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:51 volumio volumio[956]: info: Jan 11 00:52:51 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:51 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:51 volumio volumio[956]: info: Jan 11 00:52:51 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:51 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand status took 19 milliseconds Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand status took 18 milliseconds Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand status took 16 milliseconds Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:51 volumio volumio[956]: info: Jan 11 00:52:51 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:51 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand clearerror took 29 milliseconds Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand clearerror took 27 milliseconds Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand clearerror took 25 milliseconds Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand status took 7 milliseconds Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:51 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:51 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24jazz","artist":"24Jazz","album":null,"uri":"http://listen.shoutcast.com/24jazz","trackType":"com/24jazz"} Jan 11 00:52:51 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: No code Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:51 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24jazz","artist":"24Jazz","album":null,"uri":"http://listen.shoutcast.com/24jazz","trackType":"com/24jazz"} Jan 11 00:52:51 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: No code Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:51 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24jazz","artist":"24Jazz","album":null,"uri":"http://listen.shoutcast.com/24jazz","trackType":"com/24jazz"} Jan 11 00:52:51 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: No code Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: ------------------------------ 203ms Jan 11 00:52:51 volumio volumio[956]: info: ------------------------------ 202ms Jan 11 00:52:51 volumio volumio[956]: info: ------------------------------ 200ms Jan 11 00:52:51 volumio volumio[956]: info: sendMpdCommand playlistinfo took 173 milliseconds Jan 11 00:52:51 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:51 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:51 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24jazz","artist":"24Jazz","album":null,"uri":"http://listen.shoutcast.com/24jazz","trackType":"com/24jazz"} Jan 11 00:52:51 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: No code Jan 11 00:52:51 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:51 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:51 volumio volumio[956]: info: ------------------------------ 247ms Jan 11 00:52:52 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:52 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:52 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:52:52 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:52 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:52:52 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:52:52 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:52 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99335150 Jan 11 00:52:52 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:52:52 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:52 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:52:52 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:52:52 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:52 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:52 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:52:52 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:52 volumio volumio[956]: info: [1768053172924] ControllerWebradio::clearAddPlayTrack Jan 11 00:52:52 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:52:52 volumio volumio[956]: info: sendMpdCommand stop took 5 milliseconds Jan 11 00:52:52 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:52:52 volumio volumio[956]: info: Jan 11 00:52:52 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:52 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:52 volumio volumio[956]: info: sendMpdCommand clear took 3 milliseconds Jan 11 00:52:52 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99335150" Jan 11 00:52:52 volumio volumio[956]: info: Jan 11 00:52:52 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:52 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:52 volumio volumio[956]: info: Jan 11 00:52:52 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:52 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:52 volumio volumio[956]: info: Jan 11 00:52:52 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:52 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:52 volumio volumio[956]: error: updateQueue error: null Jan 11 00:52:52 volumio volumio[956]: info: ------------------------------ 14ms Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99335150" took 246 milliseconds Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:52:53 volumio volumio[956]: info: Jan 11 00:52:53 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:53 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 251ms Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 250ms Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 247ms Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand play took 7 milliseconds Jan 11 00:52:53 volumio volumio[956]: info: Jan 11 00:52:53 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:53 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:53 volumio volumio[956]: info: Jan 11 00:52:53 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:53 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:53 volumio volumio[956]: info: Jan 11 00:52:53 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:53 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 15ms Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 10ms Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 8ms Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 6ms Jan 11 00:52:53 volumio volumio[956]: info: Jan 11 00:52:53 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:53 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:53 volumio volumio[956]: info: Jan 11 00:52:53 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:53 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:53 volumio volumio[956]: info: Jan 11 00:52:53 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:53 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand status took 24 milliseconds Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand status took 22 milliseconds Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:53 volumio volumio[956]: info: Jan 11 00:52:53 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:53 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand status took 45 milliseconds Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand clearerror took 30 milliseconds Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand clearerror took 27 milliseconds Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand status took 15 milliseconds Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:53 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:53 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioshadhin","artist":"Radio Shadhin","album":null,"uri":"http://listen.shoutcast.com/radioshadhin","trackType":""} Jan 11 00:52:53 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: No code Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:53 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioshadhin","artist":"Radio Shadhin","album":null,"uri":"http://listen.shoutcast.com/radioshadhin","trackType":""} Jan 11 00:52:53 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: No code Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 184ms Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 182ms Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand clearerror took 150 milliseconds Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand playlistinfo took 146 milliseconds Jan 11 00:52:53 volumio volumio[956]: info: sendMpdCommand playlistinfo took 145 milliseconds Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:53 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:53 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:53 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioshadhin","artist":"Radio Shadhin","album":null,"uri":"http://listen.shoutcast.com/radioshadhin","trackType":""} Jan 11 00:52:53 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: No code Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:53 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radioshadhin","artist":"Radio Shadhin","album":null,"uri":"http://listen.shoutcast.com/radioshadhin","trackType":""} Jan 11 00:52:53 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: No code Jan 11 00:52:53 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 295ms Jan 11 00:52:53 volumio volumio[956]: info: ------------------------------ 264ms Jan 11 00:52:55 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:55 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:55 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:52:55 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:55 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:52:55 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:52:55 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:55 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99338546 Jan 11 00:52:55 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:52:55 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:55 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:52:55 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:52:55 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:55 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:55 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:52:55 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:55 volumio volumio[956]: info: [1768053175923] ControllerWebradio::clearAddPlayTrack Jan 11 00:52:55 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:52:55 volumio volumio[956]: info: sendMpdCommand stop took 9 milliseconds Jan 11 00:52:55 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:52:55 volumio volumio[956]: info: Jan 11 00:52:55 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:55 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:55 volumio volumio[956]: info: sendMpdCommand clear took 3 milliseconds Jan 11 00:52:55 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99338546" Jan 11 00:52:55 volumio volumio[956]: info: Jan 11 00:52:55 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:55 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:55 volumio volumio[956]: info: Jan 11 00:52:55 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:55 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:55 volumio volumio[956]: info: Jan 11 00:52:55 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:55 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:55 volumio volumio[956]: error: updateQueue error: null Jan 11 00:52:55 volumio volumio[956]: info: ------------------------------ 13ms Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99338546" took 246 milliseconds Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:52:56 volumio volumio[956]: info: Jan 11 00:52:56 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:56 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 250ms Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 248ms Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 246ms Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand play took 6 milliseconds Jan 11 00:52:56 volumio volumio[956]: info: Jan 11 00:52:56 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:56 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:56 volumio volumio[956]: info: Jan 11 00:52:56 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:56 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:56 volumio volumio[956]: info: Jan 11 00:52:56 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:56 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 18ms Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 13ms Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 11ms Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 7ms Jan 11 00:52:56 volumio volumio[956]: info: Jan 11 00:52:56 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:56 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:56 volumio volumio[956]: info: Jan 11 00:52:56 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:56 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:56 volumio volumio[956]: info: Jan 11 00:52:56 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:56 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand status took 33 milliseconds Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand status took 33 milliseconds Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:56 volumio volumio[956]: info: Jan 11 00:52:56 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:56 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand status took 43 milliseconds Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand clearerror took 33 milliseconds Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand clearerror took 30 milliseconds Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand status took 16 milliseconds Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:56 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:56 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"freeravecz-teknoradio","artist":"FREERAVE.CZ - Tekno radio","album":null,"uri":"http://listen.shoutcast.com/freeravecz-teknoradio","trackType":""} Jan 11 00:52:56 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: No code Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:56 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"freeravecz-teknoradio","artist":"FREERAVE.CZ - Tekno radio","album":null,"uri":"http://listen.shoutcast.com/freeravecz-teknoradio","trackType":""} Jan 11 00:52:56 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: No code Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 179ms Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 179ms Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand clearerror took 133 milliseconds Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand playlistinfo took 122 milliseconds Jan 11 00:52:56 volumio volumio[956]: info: sendMpdCommand playlistinfo took 121 milliseconds Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:56 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:56 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:56 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"freeravecz-teknoradio","artist":"FREERAVE.CZ - Tekno radio","album":null,"uri":"http://listen.shoutcast.com/freeravecz-teknoradio","trackType":""} Jan 11 00:52:56 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: No code Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:56 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"freeravecz-teknoradio","artist":"FREERAVE.CZ - Tekno radio","album":null,"uri":"http://listen.shoutcast.com/freeravecz-teknoradio","trackType":""} Jan 11 00:52:56 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: No code Jan 11 00:52:56 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:56 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 280ms Jan 11 00:52:56 volumio volumio[956]: info: ------------------------------ 251ms Jan 11 00:52:58 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:58 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:58 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:52:58 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:58 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:52:58 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:52:58 volumio volumio[956]: info: Preload queue cleared Jan 11 00:52:58 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99211556 Jan 11 00:52:58 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:52:58 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:52:58 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:52:58 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:52:58 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:52:58 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:52:58 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:58 volumio volumio[956]: info: [1768053178517] ControllerWebradio::clearAddPlayTrack Jan 11 00:52:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:52:58 volumio volumio[956]: info: sendMpdCommand stop took 6 milliseconds Jan 11 00:52:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:52:58 volumio volumio[956]: info: Jan 11 00:52:58 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:58 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:58 volumio volumio[956]: info: sendMpdCommand clear took 3 milliseconds Jan 11 00:52:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99211556" Jan 11 00:52:58 volumio volumio[956]: info: Jan 11 00:52:58 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:58 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:58 volumio volumio[956]: info: Jan 11 00:52:58 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:58 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:58 volumio volumio[956]: info: Jan 11 00:52:58 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:58 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:58 volumio volumio[956]: error: updateQueue error: null Jan 11 00:52:58 volumio volumio[956]: info: ------------------------------ 12ms Jan 11 00:52:58 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99211556" took 246 milliseconds Jan 11 00:52:58 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:52:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:52:58 volumio volumio[956]: info: Jan 11 00:52:58 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:58 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:58 volumio volumio[956]: info: ------------------------------ 269ms Jan 11 00:52:58 volumio volumio[956]: info: ------------------------------ 267ms Jan 11 00:52:58 volumio volumio[956]: info: ------------------------------ 265ms Jan 11 00:52:58 volumio volumio[956]: info: sendMpdCommand play took 24 milliseconds Jan 11 00:52:58 volumio volumio[956]: info: Jan 11 00:52:58 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:58 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:58 volumio volumio[956]: info: Jan 11 00:52:58 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:58 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:58 volumio volumio[956]: info: Jan 11 00:52:58 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:52:58 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:52:58 volumio volumio[956]: info: ------------------------------ 16ms Jan 11 00:52:58 volumio volumio[956]: info: ------------------------------ 11ms Jan 11 00:52:58 volumio volumio[956]: info: ------------------------------ 8ms Jan 11 00:52:58 volumio volumio[956]: info: ------------------------------ 7ms Jan 11 00:52:59 volumio volumio[956]: info: Jan 11 00:52:59 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:59 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:59 volumio volumio[956]: info: Jan 11 00:52:59 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:59 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand status took 14 milliseconds Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:59 volumio volumio[956]: info: Jan 11 00:52:59 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:59 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:59 volumio volumio[956]: info: Jan 11 00:52:59 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:52:59 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand status took 33 milliseconds Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand clearerror took 23 milliseconds Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand playlistinfo took 22 milliseconds Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand status took 19 milliseconds Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand status took 17 milliseconds Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:52:59 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:59 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazzvibe","artist":"JAZZ Vibe","album":null,"uri":"http://listen.shoutcast.com/jazzvibe","trackType":""} Jan 11 00:52:59 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: No code Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: ------------------------------ 101ms Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand clearerror took 74 milliseconds Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand playlistinfo took 69 milliseconds Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand playlistinfo took 68 milliseconds Jan 11 00:52:59 volumio volumio[956]: info: sendMpdCommand playlistinfo took 68 milliseconds Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:59 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:52:59 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:59 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazzvibe","artist":"JAZZ Vibe","album":null,"uri":"http://listen.shoutcast.com/jazzvibe","trackType":""} Jan 11 00:52:59 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: No code Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:59 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazzvibe","artist":"JAZZ Vibe","album":null,"uri":"http://listen.shoutcast.com/jazzvibe","trackType":""} Jan 11 00:52:59 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: No code Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:52:59 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazzvibe","artist":"JAZZ Vibe","album":null,"uri":"http://listen.shoutcast.com/jazzvibe","trackType":""} Jan 11 00:52:59 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: No code Jan 11 00:52:59 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:52:59 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:52:59 volumio volumio[956]: info: ------------------------------ 271ms Jan 11 00:52:59 volumio volumio[956]: info: ------------------------------ 258ms Jan 11 00:52:59 volumio volumio[956]: info: ------------------------------ 256ms Jan 11 00:53:00 volumio volumio[956]: info: Preload queue cleared Jan 11 00:53:00 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:53:00 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:53:00 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:53:00 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:53:00 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:53:00 volumio volumio[956]: info: Preload queue cleared Jan 11 00:53:00 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99222154 Jan 11 00:53:00 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:53:00 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:53:00 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:53:00 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:53:00 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:53:00 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:00 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:53:00 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:00 volumio volumio[956]: info: [1768053180790] ControllerWebradio::clearAddPlayTrack Jan 11 00:53:00 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:53:00 volumio volumio[956]: info: sendMpdCommand stop took 5 milliseconds Jan 11 00:53:00 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:53:00 volumio volumio[956]: info: Jan 11 00:53:00 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:00 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:00 volumio volumio[956]: info: sendMpdCommand clear took 3 milliseconds Jan 11 00:53:00 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99222154" Jan 11 00:53:00 volumio volumio[956]: info: Jan 11 00:53:00 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:00 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:00 volumio volumio[956]: info: Jan 11 00:53:00 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:00 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:00 volumio volumio[956]: info: Jan 11 00:53:00 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:00 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:00 volumio volumio[956]: error: updateQueue error: null Jan 11 00:53:00 volumio volumio[956]: info: ------------------------------ 13ms Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99222154" took 248 milliseconds Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:53:01 volumio volumio[956]: info: Jan 11 00:53:01 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:01 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 253ms Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 250ms Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 249ms Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand play took 5 milliseconds Jan 11 00:53:01 volumio volumio[956]: info: Jan 11 00:53:01 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:01 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:01 volumio volumio[956]: info: Jan 11 00:53:01 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:01 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:01 volumio volumio[956]: info: Jan 11 00:53:01 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:01 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 13ms Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 9ms Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 6ms Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 5ms Jan 11 00:53:01 volumio volumio[956]: info: Jan 11 00:53:01 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:01 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:01 volumio volumio[956]: info: Jan 11 00:53:01 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:01 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:01 volumio volumio[956]: info: Jan 11 00:53:01 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:01 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:01 volumio volumio[956]: info: Jan 11 00:53:01 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:01 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand status took 26 milliseconds Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand status took 25 milliseconds Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand status took 24 milliseconds Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand status took 48 milliseconds Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand clearerror took 32 milliseconds Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand clearerror took 30 milliseconds Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand clearerror took 29 milliseconds Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:01 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:01 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"toxicfmradio","artist":"ToxicFMRadio","album":null,"uri":"http://listen.shoutcast.com/toxicfmradio","trackType":""} Jan 11 00:53:01 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: No code Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:01 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"toxicfmradio","artist":"ToxicFMRadio","album":null,"uri":"http://listen.shoutcast.com/toxicfmradio","trackType":""} Jan 11 00:53:01 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: No code Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:01 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"toxicfmradio","artist":"ToxicFMRadio","album":null,"uri":"http://listen.shoutcast.com/toxicfmradio","trackType":""} Jan 11 00:53:01 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: No code Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 197ms Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 195ms Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 193ms Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand clearerror took 173 milliseconds Jan 11 00:53:01 volumio volumio[956]: info: sendMpdCommand playlistinfo took 169 milliseconds Jan 11 00:53:01 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:01 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:01 volumio volumio[956]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"toxicfmradio","artist":"ToxicFMRadio","album":null,"uri":"http://listen.shoutcast.com/toxicfmradio","trackType":""} Jan 11 00:53:01 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: No code Jan 11 00:53:01 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:01 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:01 volumio volumio[956]: info: ------------------------------ 271ms Jan 11 00:53:12 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 11 00:53:13 volumio volumio[956]: info: Preload queue cleared Jan 11 00:53:15 volumio volumio[956]: info: Preload queue cleared Jan 11 00:53:15 volumio volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::ClearQueue Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:53:15 volumio volumio[956]: info: CorePlayQueue::clearPlayQueue Jan 11 00:53:15 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:53:15 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::addQueueItems Jan 11 00:53:15 volumio volumio[956]: info: CorePlayQueue::addQueueItems Jan 11 00:53:15 volumio volumio[956]: info: Preload queue cleared Jan 11 00:53:15 volumio volumio[956]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99550239 Jan 11 00:53:15 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 11 00:53:15 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Jan 11 00:53:15 volumio volumio[956]: info: CorePlayQueue::saveQueue Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 11 00:53:15 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 11 00:53:15 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::play index 0 Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::stop Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 00:53:15 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:15 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 11 00:53:15 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:15 volumio volumio[956]: info: [1768053195825] ControllerWebradio::clearAddPlayTrack Jan 11 00:53:15 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 00:53:15 volumio volumio[956]: info: sendMpdCommand stop took 4 milliseconds Jan 11 00:53:15 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 00:53:15 volumio volumio[956]: info: Jan 11 00:53:15 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:15 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:15 volumio volumio[956]: info: sendMpdCommand clear took 2 milliseconds Jan 11 00:53:15 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99550239" Jan 11 00:53:15 volumio volumio[956]: info: Jan 11 00:53:15 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:15 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:15 volumio volumio[956]: info: Jan 11 00:53:15 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:15 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:15 volumio volumio[956]: info: Jan 11 00:53:15 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:15 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:15 volumio volumio[956]: error: updateQueue error: null Jan 11 00:53:15 volumio volumio[956]: info: ------------------------------ 12ms Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99550239" took 246 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:16 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 251ms Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 248ms Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 247ms Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand play took 5 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:16 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:16 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:16 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 13ms Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 9ms Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 7ms Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 5ms Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand status took 63 milliseconds Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand status took 66 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand status took 63 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand status took 61 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: 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":"stream","artist":"(#1 - 2/1000) KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:16 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 82ms Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:16 volumio volumio[956]: info: Jan 11 00:53:16 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand status took 22 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand status took 11 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand status took 9 milliseconds Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: 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":"stream","artist":"(#1 - 2/1000) KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:16 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:16 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: 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":"stream","artist":"(#1 - 2/1000) KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:16 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:16 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: 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":"stream","artist":"(#1 - 2/1000) KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:16 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:16 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 200ms Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 199ms Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 203ms Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand status took 126 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand playlistinfo took 118 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand playlistinfo took 116 milliseconds Jan 11 00:53:16 volumio volumio[956]: info: sendMpdCommand playlistinfo took 115 milliseconds Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:16 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: 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":"stream","artist":"(#1 - 2/1000) KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:16 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:16 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: 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":"stream","artist":"(#1 - 2/1000) KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:16 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:16 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: 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":"stream","artist":"(#1 - 2/1000) KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:16 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:16 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:16 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 299ms Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 287ms Jan 11 00:53:16 volumio volumio[956]: info: ------------------------------ 286ms Jan 11 00:53:17 volumio volumio[956]: info: Jan 11 00:53:17 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:17 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:17 volumio volumio[956]: info: Jan 11 00:53:17 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:17 volumio volumio[956]: info: sendMpdCommand playlistinfo took 184 milliseconds Jan 11 00:53:17 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:17 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: 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":"stream","artist":"(#1 - 2/1000) KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:17 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:17 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: ------------------------------ 361ms Jan 11 00:53:17 volumio volumio[956]: info: Jan 11 00:53:17 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:17 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:17 volumio volumio[956]: info: Jan 11 00:53:17 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:17 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:17 volumio volumio[956]: info: Jan 11 00:53:17 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:17 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:17 volumio volumio[956]: info: Jan 11 00:53:17 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:17 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:17 volumio volumio[956]: info: Jan 11 00:53:17 volumio volumio[956]: ---------------------------- MPD announces system playlist update Jan 11 00:53:17 volumio volumio[956]: info: Ignoring MPD Status Update Jan 11 00:53:17 volumio volumio[956]: info: Jan 11 00:53:17 volumio volumio[956]: ---------------------------- MPD announces state update: player Jan 11 00:53:17 volumio volumio[956]: info: ControllerMpd::getState Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jan 11 00:53:17 volumio volumio[956]: info: ------------------------------ 74ms Jan 11 00:53:17 volumio volumio[956]: info: sendMpdCommand status took 71 milliseconds Jan 11 00:53:17 volumio volumio[956]: info: ------------------------------ 24ms Jan 11 00:53:17 volumio volumio[956]: info: sendMpdCommand status took 23 milliseconds Jan 11 00:53:17 volumio volumio[956]: info: ------------------------------ 22ms Jan 11 00:53:17 volumio volumio[956]: info: sendMpdCommand status took 20 milliseconds Jan 11 00:53:17 volumio volumio[956]: info: ------------------------------ 19ms Jan 11 00:53:17 volumio volumio[956]: info: sendMpdCommand status took 18 milliseconds Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::parseState Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 00:53:17 volumio volumio[956]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 11 00:53:17 volumio volumio[956]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 11 00:53:17 volumio volumio[956]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 11 00:53:17 volumio volumio[956]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:17 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Jan 11 00:53:17 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:17 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:17 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:17 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:17 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:17 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:17 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: ControllerMpd::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"KBIF 900 AM","album":null,"uri":"http://167.114.103.39:8828/stream","trackType":""} Jan 11 00:53:17 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Jan 11 00:53:17 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: CoreStateMachine::pushState Jan 11 00:53:17 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 11 00:53:17 volumio volumio[956]: info: ------------------------------ 239ms Jan 11 00:53:17 volumio volumio[956]: info: ------------------------------ 191ms Jan 11 00:53:17 volumio volumio[956]: info: ------------------------------ 190ms Jan 11 00:53:17 volumio volumio[956]: info: ------------------------------ 190ms Jan 11 00:53:18 volumio volumio[956]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 00:53:18 volumio volumio[956]: Error: connect ECONNREFUSED 0.0.0.0:443 Jan 11 00:53:18 volumio volumio[956]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 11 00:53:18 volumio volumio[956]: errno: -111, Jan 11 00:53:18 volumio volumio[956]: code: 'ECONNREFUSED', Jan 11 00:53:18 volumio volumio[956]: syscall: 'connect', Jan 11 00:53:18 volumio volumio[956]: address: '0.0.0.0', Jan 11 00:53:18 volumio volumio[956]: port: 443 Jan 11 00:53:18 volumio volumio[956]: } Jan 11 00:53:18 volumio volumio[956]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 00:53:19 volumio sudo[1946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 00:52 Jan 11 00:53:19 volumio sudo[1946]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"