-- Logs begin at Tue 2024-07-23 01:22:29 CST, end at Sat 2024-09-07 14:46:16 CST. -- Sep 07 14:45:05 volumio volumio[873]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::volumioGetState Sep 07 14:45:05 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:05 volumio volumio[873]: info: Restoring Previous Volume level: 100 false true Sep 07 14:45:05 volumio volumio[873]: info: VolumeController::SetAlsaVolume100 Sep 07 14:45:05 volumio volumio[873]: info: Enable softmixer device for audio device number 2 Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::volumioStop Sep 07 14:45:05 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:05 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:05 volumio volumio[873]: info: Enable softmixer device for audio device undefined Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 07 14:45:05 volumio volumio[873]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 07 14:45:05 volumio volumio[873]: info: Disabling external Volume Control Sep 07 14:45:05 volumio volumio[873]: info: Output device has changed, restarting MPD Sep 07 14:45:05 volumio sudo[3118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 14:45:05 volumio sudo[3118]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:05 volumio volumio[873]: info: Output device has changed, restarting Shairport Sync Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:05 volumio sudo[3118]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:05 volumio sudo[3121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 14:45:05 volumio sudo[3121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:05 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 14:45:05 volumio volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 14:45:05 volumio volumio[873]: info: Volume configurations have been set Sep 07 14:45:05 volumio systemd[1]: mpd.service: Succeeded. Sep 07 14:45:05 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 07 14:45:05 volumio volumio[873]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 07 14:45:05 volumio volumio[873]: info: Disabling external Volume Control Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 14:45:05 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:05 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:05 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:05 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:05 volumio systemd[1]: Starting Music Player Daemon... Sep 07 14:45:05 volumio volumio[873]: info: MPD Permissions set Sep 07 14:45:05 volumio volumio[873]: info: Software Volume ALSA configuration written Sep 07 14:45:05 volumio volumio[873]: info: Preparing to generate the ALSA configuration file Sep 07 14:45:05 volumio volumio[873]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 07 14:45:05 volumio volumio[873]: info: Reading ALSA contributions from plugins. Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:05 volumio sudo[3126]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 14:45:05 volumio sudo[3126]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:05 volumio sudo[3126]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 07 14:45:05 volumio volumio[873]: info: Getting Alsa Cards List without I2S DAC Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 07 14:45:05 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 07 14:45:06 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:06 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:06 volumio volumio[873]: info: Starting Shairport Sync Sep 07 14:45:06 volumio volumio[873]: info: Asound.conf file written Sep 07 14:45:06 volumio sudo[3153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 14:45:06 volumio sudo[3153]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 07 14:45:06 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 07 14:45:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:06 volumio sudo[3156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 07 14:45:06 volumio sudo[3156]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:06 volumio sudo[3156]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:06 volumio sudo[3153]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:06 volumio volumio[873]: info: Output device has changed, restarting MPD Sep 07 14:45:06 volumio sudo[3164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 14:45:06 volumio volumio[873]: info: Output device has changed, restarting Shairport Sync Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:06 volumio sudo[3164]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:06 volumio sudo[3164]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:06 volumio sudo[3167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 14:45:06 volumio sudo[3167]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:06 volumio volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 14:45:06 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 07 14:45:06 volumio systemd[1]: mpd.service: Succeeded. Sep 07 14:45:06 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 14:45:06 volumio systemd[1]: Starting Music Player Daemon... Sep 07 14:45:06 volumio sudo[3174]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 14:45:06 volumio sudo[3174]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:06 volumio sudo[3174]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:06 volumio mpd[3177]: Sep 07 14:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 14:45:06 volumio systemd[1]: Started Music Player Daemon. Sep 07 14:45:06 volumio sudo[3167]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:07 volumio sudo[3121]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:07 volumio dnsmasq-dhcp[862]: no address range available for DHCP request via wlan0 Sep 07 14:45:07 volumio volumio[873]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Sep 07 14:45:07 volumio volumio[873]: info: Output device has changed, restarting MPD Sep 07 14:45:07 volumio sudo[3183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 14:45:07 volumio sudo[3183]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:07 volumio volumio[873]: info: Output device has changed, restarting Shairport Sync Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:07 volumio sudo[3183]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:07 volumio sudo[3186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 14:45:07 volumio sudo[3186]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:07 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 14:45:07 volumio volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 14:45:07 volumio systemd[1]: mpd.service: Succeeded. Sep 07 14:45:07 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 07 14:45:07 volumio volumio[873]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 07 14:45:07 volumio volumio[873]: info: Disabling external Volume Control Sep 07 14:45:07 volumio systemd[1]: Starting Music Player Daemon... Sep 07 14:45:07 volumio volumio[873]: info: VolumeController::SetAlsaVolume0 Sep 07 14:45:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:07 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:07 volumio volumio[873]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 07 14:45:07 volumio volumio[873]: info: MPD Permissions set Sep 07 14:45:07 volumio volumio[873]: info: MPD Permissions set Sep 07 14:45:07 volumio volumio[873]: info: Shairport-Sync Started Sep 07 14:45:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:07 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio sudo[3192]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 14:45:07 volumio sudo[3192]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio sudo[3192]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:07 volumio volumio[873]: info: Starting Shairport Sync Sep 07 14:45:07 volumio volumio[873]: info: Starting Shairport Sync Sep 07 14:45:07 volumio sudo[3214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 14:45:07 volumio sudo[3214]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:07 volumio sudo[3217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 14:45:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 07 14:45:07 volumio sudo[3217]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:07 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 07 14:45:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:08 volumio sudo[3214]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 07 14:45:08 volumio volumio[873]: info: Shairport-Sync Started Sep 07 14:45:08 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 07 14:45:08 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 07 14:45:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:08 volumio sudo[3217]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:08 volumio volumio[873]: info: Shairport-Sync Started Sep 07 14:45:08 volumio mpd[3204]: Sep 07 14:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 14:45:08 volumio systemd[1]: Started Music Player Daemon. Sep 07 14:45:08 volumio sudo[3186]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:08 volumio volumio[873]: error: MPD error: The expression evaluated to a falsy value: Sep 07 14:45:08 volumio volumio[873]: assert.ok(self.idling) Sep 07 14:45:08 volumio volumio[873]: error: The expression evaluated to a falsy value: Sep 07 14:45:08 volumio volumio[873]: assert.ok(self.idling) Sep 07 14:45:08 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:08 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:09 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Sep 07 14:45:09 volumio sudo[3227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 14:45:09 volumio sudo[3227]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:09 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 14:45:09 volumio systemd[1]: mpd.service: Succeeded. Sep 07 14:45:09 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 14:45:09 volumio systemd[1]: Starting Music Player Daemon... Sep 07 14:45:09 volumio sudo[3229]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 14:45:09 volumio sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:09 volumio sudo[3229]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:10 volumio mpd[3231]: Sep 07 14:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 14:45:10 volumio systemd[1]: Started Music Player Daemon. Sep 07 14:45:10 volumio sudo[3227]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:10 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:10 volumio volumio[873]: info: VolumeController::SetAlsaVolume100 Sep 07 14:45:10 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:10 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:10 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:10 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:10 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::volumioGetState Sep 07 14:45:13 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:13 volumio volumio[873]: info: Restoring Previous Volume level: 100 false false Sep 07 14:45:13 volumio volumio[873]: info: Disable softmixer device for audio device Sep 07 14:45:13 volumio volumio[873]: info: Output device has changed, restarting MPD Sep 07 14:45:13 volumio sudo[3236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 14:45:13 volumio sudo[3236]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:13 volumio volumio[873]: info: Output device has changed, restarting Shairport Sync Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:13 volumio sudo[3236]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:13 volumio sudo[3239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 14:45:13 volumio sudo[3239]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:13 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 14:45:13 volumio volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 14:45:13 volumio volumio[873]: info: Volume configurations have been set Sep 07 14:45:13 volumio systemd[1]: mpd.service: Succeeded. Sep 07 14:45:13 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 07 14:45:13 volumio volumio[873]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 07 14:45:13 volumio volumio[873]: info: Disabling external Volume Control Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 14:45:13 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:13 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:13 volumio systemd[1]: Starting Music Player Daemon... Sep 07 14:45:13 volumio volumio[873]: info: Soft Volume ALSA configuration file deleted Sep 07 14:45:13 volumio volumio[873]: info: Preparing to generate the ALSA configuration file Sep 07 14:45:13 volumio volumio[873]: info: MPD Permissions set Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio sudo[3244]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 14:45:13 volumio sudo[3244]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:13 volumio sudo[3244]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 07 14:45:13 volumio volumio[873]: info: Getting Alsa Cards List without I2S DAC Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 07 14:45:13 volumio volumio[873]: info: Starting Shairport Sync Sep 07 14:45:13 volumio volumio[873]: info: Asound.conf file written Sep 07 14:45:13 volumio sudo[3263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 14:45:13 volumio sudo[3263]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:13 volumio sudo[3266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 07 14:45:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 07 14:45:13 volumio sudo[3266]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:13 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 07 14:45:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:13 volumio sudo[3266]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:13 volumio sudo[3263]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:13 volumio volumio[873]: info: Output device has changed, restarting MPD Sep 07 14:45:13 volumio sudo[3274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 14:45:13 volumio volumio[873]: info: Output device has changed, restarting Shairport Sync Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:13 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:13 volumio sudo[3274]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:13 volumio sudo[3274]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:13 volumio sudo[3276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 14:45:13 volumio sudo[3276]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:14 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 07 14:45:14 volumio systemd[1]: mpd.service: Succeeded. Sep 07 14:45:14 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 14:45:14 volumio volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 14:45:14 volumio volumio[873]: info: MPD Permissions set Sep 07 14:45:14 volumio volumio[873]: info: Shairport-Sync Started Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:14 volumio volumio[873]: info: Starting Shairport Sync Sep 07 14:45:14 volumio systemd[1]: Starting Music Player Daemon... Sep 07 14:45:14 volumio sudo[3288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 14:45:14 volumio sudo[3288]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 07 14:45:14 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 07 14:45:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 14:45:14 volumio sudo[3288]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:14 volumio volumio[873]: info: Shairport-Sync Started Sep 07 14:45:14 volumio sudo[3282]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 14:45:14 volumio sudo[3282]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:14 volumio sudo[3282]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:14 volumio mpd[3294]: Sep 07 14:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 14:45:14 volumio systemd[1]: Started Music Player Daemon. Sep 07 14:45:14 volumio sudo[3276]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:14 volumio sudo[3239]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:14 volumio volumio[873]: error: MPD error: The expression evaluated to a falsy value: Sep 07 14:45:14 volumio volumio[873]: assert.ok(self.idling) Sep 07 14:45:14 volumio volumio[873]: error: The expression evaluated to a falsy value: Sep 07 14:45:14 volumio volumio[873]: assert.ok(self.idling) Sep 07 14:45:14 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:17 volumio volumio[873]: info: VolumeController::SetAlsaVolume100 Sep 07 14:45:17 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:17 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:17 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:20 volumio volumio[873]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Sep 07 14:45:20 volumio sudo[3340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 14:45:20 volumio sudo[3340]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:20 volumio sudo[3340]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:20 volumio volumio[873]: info: MPD Permissions set Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio sudo[3343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 14:45:20 volumio sudo[3343]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:20 volumio systemd[1]: Stopping Music Player Daemon... Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Sep 07 14:45:20 volumio systemd[1]: mpd.service: Succeeded. Sep 07 14:45:20 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 14:45:20 volumio sudo[3351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 07 14:45:20 volumio sudo[3351]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:20 volumio volumio[873]: info: MPD Permissions set Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 14:45:20 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 14:45:20 volumio sudo[3351]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:20 volumio sudo[3354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 07 14:45:20 volumio sudo[3354]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:20 volumio systemd[1]: Starting Music Player Daemon... Sep 07 14:45:20 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 07 14:45:20 volumio systemd[1]: mpd.service: Succeeded. Sep 07 14:45:20 volumio systemd[1]: Stopped Music Player Daemon. Sep 07 14:45:20 volumio systemd[1]: Starting Music Player Daemon... Sep 07 14:45:20 volumio sudo[3361]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 07 14:45:20 volumio sudo[3361]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:45:20 volumio sudo[3361]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:20 volumio mpd[3363]: Sep 07 14:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 14:45:20 volumio systemd[1]: Started Music Player Daemon. Sep 07 14:45:20 volumio sudo[3354]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:20 volumio sudo[3343]: pam_unix(sudo:session): session closed for user root Sep 07 14:45:20 volumio volumio[873]: error: MPD error: The expression evaluated to a falsy value: Sep 07 14:45:20 volumio volumio[873]: assert.ok(self.idling) Sep 07 14:45:20 volumio volumio[873]: error: The expression evaluated to a falsy value: Sep 07 14:45:20 volumio volumio[873]: assert.ok(self.idling) Sep 07 14:45:20 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:25 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 14:45:25 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 14:45:25 volumio volumio[873]: info: Discovery: Getting this device information Sep 07 14:45:25 volumio volumio[873]: info: CoreCommandRouter::volumioGetState Sep 07 14:45:25 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:25 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 14:45:30 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 14:45:30 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 14:45:30 volumio volumio[873]: info: Discovery: Getting this device information Sep 07 14:45:30 volumio volumio[873]: info: CoreCommandRouter::volumioGetState Sep 07 14:45:30 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:30 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 14:45:32 volumio volumio[873]: info: CoreCommandRouter::volumioGetState Sep 07 14:45:32 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:37 volumio dnsmasq-dhcp[862]: no address range available for DHCP request via wlan0 Sep 07 14:45:38 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 07 14:45:38 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 14:45:39 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:43 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 07 14:45:45 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:45 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:45:45 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:45 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:45 volumio volumio[873]: info: [1725691545569] ControllerWebradio::clearAddPlayTrack Sep 07 14:45:45 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:45:45 volumio volumio[873]: info: sendMpdCommand stop took 1 milliseconds Sep 07 14:45:45 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:45:45 volumio volumio[873]: info: Sep 07 14:45:45 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:45 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:45 volumio volumio[873]: info: sendMpdCommand clear took 1 milliseconds Sep 07 14:45:45 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:45:45 volumio volumio[873]: info: Sep 07 14:45:45 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:45 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:45 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:45 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:45:45 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:45:45 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:45 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:45:45 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:45 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:45:45 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:45 volumio volumio[873]: info: [1725691545656] ControllerWebradio::clearAddPlayTrack Sep 07 14:45:45 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:45:46 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 719 milliseconds Sep 07 14:45:46 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:45:46 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:45:46 volumio volumio[873]: info: Sep 07 14:45:46 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:46 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:46 volumio volumio[873]: info: ------------------------------ 722ms Sep 07 14:45:46 volumio volumio[873]: info: sendMpdCommand stop took 641 milliseconds Sep 07 14:45:46 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:45:46 volumio volumio[873]: info: Sep 07 14:45:46 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:46 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:46 volumio volumio[873]: info: sendMpdCommand play took 5 milliseconds Sep 07 14:45:46 volumio volumio[873]: info: ------------------------------ 4ms Sep 07 14:45:46 volumio volumio[873]: info: sendMpdCommand clear took 476 milliseconds Sep 07 14:45:46 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:45:46 volumio volumio[873]: info: Sep 07 14:45:46 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:46 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:46 volumio volumio[873]: info: Sep 07 14:45:46 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:46 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:46 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:46 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:46 volumio volumio[873]: info: ------------------------------ 477ms Sep 07 14:45:46 volumio volumio[873]: info: Sep 07 14:45:46 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:46 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:46 volumio volumio[873]: info: Sep 07 14:45:46 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:46 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:46 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 275 milliseconds Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:47 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 284ms Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand status took 282 milliseconds Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 282ms Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand status took 281 milliseconds Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:47 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:47 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:45:47 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: No code Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:47 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:45:47 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: No code Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 320ms Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 320ms Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:47 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand play took 49 milliseconds Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 42ms Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:45:47 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:45:47 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:47 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:47 volumio volumio[873]: info: [1725691547543] ControllerWebradio::clearAddPlayTrack Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand stop took 2 milliseconds Sep 07 14:45:47 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:47 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand status took 1 milliseconds Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand clear took 1 milliseconds Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:47 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:47 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 4ms Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:47 volumio volumio[873]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 6ms Sep 07 14:45:47 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:45:47 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:47 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:47 volumio volumio[873]: info: [1725691547625] ControllerWebradio::clearAddPlayTrack Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 264 milliseconds Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:47 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand status took 265 milliseconds Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand stop took 189 milliseconds Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand play took 2 milliseconds Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:45:47 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:47 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:45:47 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: No code Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 282ms Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:47 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:47 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:47 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 19ms Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand clear took 18 milliseconds Sep 07 14:45:47 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:47 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:47 volumio volumio[873]: info: Sep 07 14:45:47 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:47 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:47 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 6ms Sep 07 14:45:47 volumio volumio[873]: info: sendMpdCommand status took 5 milliseconds Sep 07 14:45:47 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:47 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:47 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:45:47 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: No code Sep 07 14:45:47 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:47 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:47 volumio volumio[873]: info: ------------------------------ 24ms Sep 07 14:45:48 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 265 milliseconds Sep 07 14:45:48 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:45:48 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:45:48 volumio volumio[873]: info: Sep 07 14:45:48 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:48 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:48 volumio volumio[873]: info: ------------------------------ 265ms Sep 07 14:45:48 volumio volumio[873]: info: sendMpdCommand status took 265 milliseconds Sep 07 14:45:48 volumio volumio[873]: info: sendMpdCommand play took 2 milliseconds Sep 07 14:45:48 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:48 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:48 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:48 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:48 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:48 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:48 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:48 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:45:48 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:48 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:45:48 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:48 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:48 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:48 volumio volumio[873]: info: No code Sep 07 14:45:48 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:48 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:48 volumio volumio[873]: info: ------------------------------ 284ms Sep 07 14:45:48 volumio volumio[873]: info: Sep 07 14:45:48 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:48 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:48 volumio volumio[873]: info: ------------------------------ 21ms Sep 07 14:45:48 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 3 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 25/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 9ms Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 4 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 25/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:45:52 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 25/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:45:52 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 34ms Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 32ms Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 26 milliseconds Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 25/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:45:52 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 44ms Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:52 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:52 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:52 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 4ms Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 3 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:52 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 5 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 4ms Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 3 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:45:52 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 21ms Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:45:52 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:45:52 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:45:52 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 61ms Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 59ms Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 58ms Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:52 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:52 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:52 volumio volumio[873]: info: Sep 07 14:45:52 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 7ms Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 6 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 5ms Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand status took 1 milliseconds Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 07 14:45:52 volumio volumio[873]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1183,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:45:52 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:52 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:52 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1183,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:45:52 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:45:52 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:52 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 39ms Sep 07 14:45:52 volumio volumio[873]: info: ------------------------------ 38ms Sep 07 14:45:53 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::stPlaybackTimer Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::serviceStop Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::serviceStop Sep 07 14:45:53 volumio volumio[873]: info: [1725691553339] ControllerWebradio::stop Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:45:53 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:53 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:53 volumio volumio[873]: info: [1725691553353] ControllerWebradio::clearAddPlayTrack Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:45:53 volumio volumio[873]: info: Sep 07 14:45:53 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:53 volumio volumio[873]: info: sendMpdCommand stop took 16 milliseconds Sep 07 14:45:53 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:53 volumio volumio[873]: info: sendMpdCommand stop took 3 milliseconds Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:45:53 volumio volumio[873]: info: Sep 07 14:45:53 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:53 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:53 volumio volumio[873]: info: Sep 07 14:45:53 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:53 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:53 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:45:53 volumio volumio[873]: info: sendMpdCommand clear took 2 milliseconds Sep 07 14:45:53 volumio volumio[873]: info: sendMpdCommand status took 1 milliseconds Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:53 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:53 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:53 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:53 volumio volumio[873]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 07 14:45:53 volumio volumio[873]: info: ------------------------------ 9ms Sep 07 14:45:53 volumio volumio[873]: info: Sep 07 14:45:53 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:53 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:53 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:53 volumio volumio[873]: info: ------------------------------ 10ms Sep 07 14:45:53 volumio volumio[873]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:53 volumio volumio[873]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 07 14:45:53 volumio volumio[873]: info: ------------------------------ 13ms Sep 07 14:45:53 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 264 milliseconds Sep 07 14:45:53 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:45:53 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:45:53 volumio volumio[873]: info: Sep 07 14:45:53 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:53 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:53 volumio volumio[873]: info: ------------------------------ 258ms Sep 07 14:45:53 volumio volumio[873]: info: sendMpdCommand play took 1 milliseconds Sep 07 14:45:53 volumio volumio[873]: info: Sep 07 14:45:53 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:53 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:53 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:45:53 volumio volumio[873]: info: ------------------------------ 1ms Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 14:45:55 volumio volumio[873]: info: Discovery: Getting this device information Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioGetState Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 14:45:55 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:45:55 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:55 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:55 volumio volumio[873]: info: [1725691555622] ControllerWebradio::clearAddPlayTrack Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:45:55 volumio volumio[873]: info: Sep 07 14:45:55 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand stop took 4 milliseconds Sep 07 14:45:55 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:45:55 volumio volumio[873]: info: Sep 07 14:45:55 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:55 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand status took 1 milliseconds Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand clear took 1 milliseconds Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:45:55 volumio volumio[873]: info: Sep 07 14:45:55 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:55 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:55 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:55 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:55 volumio volumio[873]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 07 14:45:55 volumio volumio[873]: info: ------------------------------ 6ms Sep 07 14:45:55 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:45:55 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:55 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:55 volumio volumio[873]: info: [1725691555689] ControllerWebradio::clearAddPlayTrack Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 265 milliseconds Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:45:55 volumio volumio[873]: info: Sep 07 14:45:55 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:55 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand status took 266 milliseconds Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand stop took 206 milliseconds Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand play took 1 milliseconds Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:45:55 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:55 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:45:55 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:55 volumio volumio[873]: info: No code Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:55 volumio volumio[873]: info: ------------------------------ 283ms Sep 07 14:45:55 volumio volumio[873]: info: Sep 07 14:45:55 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:55 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:55 volumio volumio[873]: info: Sep 07 14:45:55 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:55 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:55 volumio volumio[873]: info: Sep 07 14:45:55 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:55 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:55 volumio volumio[873]: info: ------------------------------ 20ms Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand clear took 18 milliseconds Sep 07 14:45:55 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:55 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:45:55 volumio volumio[873]: info: Sep 07 14:45:55 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:55 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:55 volumio volumio[873]: info: Sep 07 14:45:55 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:55 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:55 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:55 volumio volumio[873]: info: ------------------------------ 8ms Sep 07 14:45:55 volumio volumio[873]: info: sendMpdCommand status took 7 milliseconds Sep 07 14:45:55 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:55 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:55 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:55 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:45:55 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:55 volumio volumio[873]: info: No code Sep 07 14:45:55 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:55 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:55 volumio volumio[873]: info: ------------------------------ 24ms Sep 07 14:45:56 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 265 milliseconds Sep 07 14:45:56 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:45:56 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:45:56 volumio volumio[873]: info: Sep 07 14:45:56 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:56 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:56 volumio volumio[873]: info: ------------------------------ 266ms Sep 07 14:45:56 volumio volumio[873]: info: sendMpdCommand status took 265 milliseconds Sep 07 14:45:56 volumio volumio[873]: info: sendMpdCommand play took 1 milliseconds Sep 07 14:45:56 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:56 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:56 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:56 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:56 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:56 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:56 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:56 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:45:56 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:56 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:45:56 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:56 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:56 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:56 volumio volumio[873]: info: No code Sep 07 14:45:56 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:56 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:56 volumio volumio[873]: info: ------------------------------ 285ms Sep 07 14:45:56 volumio volumio[873]: info: Sep 07 14:45:56 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:56 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:56 volumio volumio[873]: info: ------------------------------ 21ms Sep 07 14:45:56 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:45:57 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:57 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:57 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:45:57 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:57 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:45:57 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:45:57 volumio volumio[873]: info: Preload queue cleared Sep 07 14:45:57 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:45:57 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:45:57 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:45:57 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:45:57 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:45:57 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:45:57 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:57 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:45:57 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:57 volumio volumio[873]: info: [1725691557980] ControllerWebradio::clearAddPlayTrack Sep 07 14:45:57 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:45:57 volumio volumio[873]: info: Sep 07 14:45:57 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:57 volumio volumio[873]: info: sendMpdCommand stop took 2 milliseconds Sep 07 14:45:57 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:57 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:57 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:45:57 volumio volumio[873]: info: Sep 07 14:45:57 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:57 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:57 volumio volumio[873]: info: sendMpdCommand status took 3 milliseconds Sep 07 14:45:57 volumio volumio[873]: info: sendMpdCommand clear took 2 milliseconds Sep 07 14:45:57 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:57 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:45:57 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:45:57 volumio volumio[873]: info: Sep 07 14:45:57 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:45:57 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:45:57 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:45:57 volumio volumio[873]: error: updateQueue error: null Sep 07 14:45:57 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:45:57 volumio volumio[873]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 07 14:45:57 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:45:57 volumio volumio[873]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 07 14:45:57 volumio volumio[873]: info: ------------------------------ 8ms Sep 07 14:45:58 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 265 milliseconds Sep 07 14:45:58 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:45:58 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:45:58 volumio volumio[873]: info: Sep 07 14:45:58 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:58 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:58 volumio volumio[873]: info: sendMpdCommand status took 266 milliseconds Sep 07 14:45:58 volumio volumio[873]: info: sendMpdCommand play took 1 milliseconds Sep 07 14:45:58 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:45:58 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:45:58 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:45:58 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:58 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:45:58 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:58 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:45:58 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:45:58 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:45:58 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:45:58 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:45:58 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:58 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:58 volumio volumio[873]: info: No code Sep 07 14:45:58 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:45:58 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:45:58 volumio volumio[873]: info: ------------------------------ 282ms Sep 07 14:45:58 volumio volumio[873]: info: Sep 07 14:45:58 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:45:58 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:45:58 volumio volumio[873]: info: ------------------------------ 18ms Sep 07 14:45:58 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:00 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:00 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:46:00 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:46:00 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:46:00 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:00 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:00 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:46:00 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:00 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:46:00 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:00 volumio volumio[873]: info: [1725691560580] ControllerWebradio::clearAddPlayTrack Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:46:00 volumio volumio[873]: info: Sep 07 14:46:00 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:00 volumio volumio[873]: info: sendMpdCommand stop took 5 milliseconds Sep 07 14:46:00 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:46:00 volumio volumio[873]: info: Sep 07 14:46:00 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:00 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:00 volumio volumio[873]: info: sendMpdCommand status took 1 milliseconds Sep 07 14:46:00 volumio volumio[873]: info: sendMpdCommand clear took 2 milliseconds Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:46:00 volumio volumio[873]: info: Sep 07 14:46:00 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:00 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:00 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:00 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:46:00 volumio volumio[873]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:00 volumio volumio[873]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 07 14:46:00 volumio volumio[873]: info: ------------------------------ 10ms Sep 07 14:46:00 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 265 milliseconds Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:46:00 volumio volumio[873]: info: Sep 07 14:46:00 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:00 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:00 volumio volumio[873]: info: sendMpdCommand status took 264 milliseconds Sep 07 14:46:00 volumio volumio[873]: info: sendMpdCommand play took 2 milliseconds Sep 07 14:46:00 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:00 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:00 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:00 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:00 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:00 volumio volumio[873]: info: No code Sep 07 14:46:00 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:00 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:00 volumio volumio[873]: info: ------------------------------ 283ms Sep 07 14:46:00 volumio volumio[873]: info: Sep 07 14:46:00 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:00 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:00 volumio volumio[873]: info: ------------------------------ 22ms Sep 07 14:46:00 volumio volumio[873]: info: ------------------------------ 1ms Sep 07 14:46:01 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:01 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:01 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:46:01 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:01 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:46:01 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:46:01 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:01 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:46:01 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:46:01 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:01 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:46:01 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:46:01 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:01 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:01 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:46:01 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:01 volumio volumio[873]: info: [1725691561969] ControllerWebradio::clearAddPlayTrack Sep 07 14:46:01 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:46:01 volumio volumio[873]: info: Sep 07 14:46:01 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:01 volumio volumio[873]: info: sendMpdCommand stop took 2 milliseconds Sep 07 14:46:01 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:01 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:01 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:46:01 volumio volumio[873]: info: Sep 07 14:46:01 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:01 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:01 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:46:01 volumio volumio[873]: info: sendMpdCommand clear took 1 milliseconds Sep 07 14:46:01 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:01 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:01 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:46:01 volumio volumio[873]: info: Sep 07 14:46:01 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:01 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:01 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:01 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:01 volumio volumio[873]: info: ------------------------------ 4ms Sep 07 14:46:01 volumio volumio[873]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 07 14:46:01 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:01 volumio volumio[873]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 07 14:46:01 volumio volumio[873]: info: ------------------------------ 6ms Sep 07 14:46:02 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:46:02 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:02 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: info: [1725691562042] ControllerWebradio::clearAddPlayTrack Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 267 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand status took 270 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand stop took 203 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand play took 5 milliseconds Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:02 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: No code Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 289ms Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:02 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 25ms Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand clear took 19 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:02 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 8ms Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand status took 7 milliseconds Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:02 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: No code Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 27ms Sep 07 14:46:02 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:46:02 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:02 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: info: [1725691562309] ControllerWebradio::clearAddPlayTrack Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 272 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 273ms Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand status took 272 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand stop took 230 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand play took 1 milliseconds Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:02 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: No code Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 291ms Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:02 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 21ms Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand clear took 19 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:02 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 6ms Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand status took 5 milliseconds Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:02 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: No code Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 21ms Sep 07 14:46:02 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:46:02 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:02 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: info: [1725691562738] ControllerWebradio::clearAddPlayTrack Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 267 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 268ms Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand status took 267 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand stop took 90 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand play took 1 milliseconds Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:02 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: No code Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 284ms Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:02 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 19ms Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand clear took 17 milliseconds Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:02 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:02 volumio volumio[873]: info: Sep 07 14:46:02 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:02 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 5ms Sep 07 14:46:02 volumio volumio[873]: info: sendMpdCommand status took 4 milliseconds Sep 07 14:46:02 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:02 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:02 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:02 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: No code Sep 07 14:46:02 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:02 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:02 volumio volumio[873]: info: ------------------------------ 22ms Sep 07 14:46:03 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:46:03 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:03 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:03 volumio volumio[873]: info: [1725691563080] ControllerWebradio::clearAddPlayTrack Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 266 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 269ms Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand status took 268 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand stop took 33 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand play took 4 milliseconds Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:46:03 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:03 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:03 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: No code Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 285ms Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:03 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 20ms Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand clear took 19 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:03 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:03 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 5ms Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand status took 5 milliseconds Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:03 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:03 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:03 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: No code Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 23ms Sep 07 14:46:03 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::ClearQueue Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::clearPlayQueue Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::addQueueItems Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::addQueueItems Sep 07 14:46:03 volumio volumio[873]: info: Preload queue cleared Sep 07 14:46:03 volumio volumio[873]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushQueue Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::saveQueue Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::updateTrackBlock Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrackBlock Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPlay Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::play index 0 Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::play index undefined Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::startPlaybackTimer Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:03 volumio volumio[873]: info: [1725691563352] ControllerWebradio::clearAddPlayTrack Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 267 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 267ms Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand status took 266 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand stop took 53 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand play took 2 milliseconds Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 14:46:03 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:03 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:03 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: No code Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 285ms Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:03 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand clear took 19 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:03 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:03 volumio volumio[873]: error: updateQueue error: null Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 5ms Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand status took 4 milliseconds Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:03 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:03 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:03 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: No code Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 22ms Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 265 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand play Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 267ms Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand status took 266 milliseconds Sep 07 14:46:03 volumio volumio[873]: info: sendMpdCommand play took 2 milliseconds Sep 07 14:46:03 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:03 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:03 volumio volumio[873]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 07 14:46:03 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState stateService stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: No code Sep 07 14:46:03 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:03 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 287ms Sep 07 14:46:03 volumio volumio[873]: info: Sep 07 14:46:03 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:03 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 25ms Sep 07 14:46:03 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:06 volumio volumio[873]: info: Sep 07 14:46:06 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:06 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:06 volumio volumio[873]: info: Sep 07 14:46:06 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:06 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:06 volumio volumio[873]: info: Sep 07 14:46:06 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:06 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:06 volumio volumio[873]: info: sendMpdCommand status took 3 milliseconds Sep 07 14:46:06 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:06 volumio volumio[873]: info: Sep 07 14:46:06 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:06 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:06 volumio volumio[873]: info: sendMpdCommand status took 3 milliseconds Sep 07 14:46:06 volumio volumio[873]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 07 14:46:06 volumio volumio[873]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 07 14:46:06 volumio volumio[873]: info: sendMpdCommand status took 1 milliseconds Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:06 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:06 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:06 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 25/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:06 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus stop Sep 07 14:46:06 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:06 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:06 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 25/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:06 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:06 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:06 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:06 volumio volumio[873]: info: ------------------------------ 19ms Sep 07 14:46:06 volumio volumio[873]: info: ------------------------------ 23ms Sep 07 14:46:06 volumio volumio[873]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 07 14:46:06 volumio volumio[873]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:06 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:06 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:06 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:06 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 25/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:06 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:06 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:06 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:06 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:06 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:06 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oldies-but-goldies","artist":"(#1 - 25/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:06 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:06 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:06 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:06 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:06 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:06 volumio volumio[873]: info: ------------------------------ 44ms Sep 07 14:46:06 volumio volumio[873]: info: ------------------------------ 43ms Sep 07 14:46:06 volumio volumio[873]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%2025%2F500000)%20ANTENNE%20BAYERN%20Oldies%20but%20Goldies%20(Germany)/ef63297c-1e6a-481b-986f-3165c12a1750.jpg' Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:07 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:07 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:07 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:07 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:07 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":160,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"oldies-but-goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:07 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:07 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:07 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:07 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":285,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:07 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:07 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 24ms Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 23ms Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:07 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 23ms Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand status took 22 milliseconds Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand status took 1 milliseconds Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:07 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:07 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:07 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:07 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:07 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:07 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:07 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:07 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 46ms Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 27ms Sep 07 14:46:07 volumio dnsmasq-dhcp[862]: no address range available for DHCP request via wlan0 Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:07 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:07 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:07 volumio volumio[873]: info: Sep 07 14:46:07 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand status took 3 milliseconds Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 07 14:46:07 volumio volumio[873]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:07 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:07 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:07 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1283,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:07 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:07 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:07 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:07 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1283,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:07 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:07 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:07 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 26ms Sep 07 14:46:07 volumio volumio[873]: info: ------------------------------ 26ms Sep 07 14:46:08 volumio volumio[873]: info: Sep 07 14:46:08 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:08 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:08 volumio volumio[873]: info: Sep 07 14:46:08 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:08 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:08 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:08 volumio volumio[873]: info: Sep 07 14:46:08 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:08 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:08 volumio volumio[873]: info: Sep 07 14:46:08 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:08 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:08 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:08 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:46:08 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:46:08 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:08 volumio volumio[873]: info: sendMpdCommand status took 5 milliseconds Sep 07 14:46:08 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:08 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:08 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:08 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:08 volumio volumio[873]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 07 14:46:08 volumio volumio[873]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 07 14:46:08 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:08 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:08 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:08 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:08 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:08 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2378,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:08 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:08 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:08 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:08 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:08 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:08 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:08 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:08 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:08 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:08 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:08 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:08 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:08 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:08 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2378,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:08 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:08 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:08 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:08 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:08 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:08 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:08 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:08 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:08 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:08 volumio volumio[873]: info: ------------------------------ 33ms Sep 07 14:46:08 volumio volumio[873]: info: ------------------------------ 32ms Sep 07 14:46:09 volumio volumio[873]: info: Sep 07 14:46:09 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:09 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:09 volumio volumio[873]: info: Sep 07 14:46:09 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:09 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:09 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:09 volumio volumio[873]: info: Sep 07 14:46:09 volumio volumio[873]: ---------------------------- MPD announces system playlist update Sep 07 14:46:09 volumio volumio[873]: info: Ignoring MPD Status Update Sep 07 14:46:09 volumio volumio[873]: info: Sep 07 14:46:09 volumio volumio[873]: ---------------------------- MPD announces state update: player Sep 07 14:46:09 volumio volumio[873]: info: ControllerMpd::getState Sep 07 14:46:09 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:46:10 volumio volumio[873]: info: ------------------------------ 3ms Sep 07 14:46:10 volumio volumio[873]: info: sendMpdCommand status took 2 milliseconds Sep 07 14:46:10 volumio volumio[873]: info: ------------------------------ 2ms Sep 07 14:46:10 volumio volumio[873]: info: sendMpdCommand status took 1 milliseconds Sep 07 14:46:10 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:10 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:10 volumio volumio[873]: verbose: ControllerMpd::parseState Sep 07 14:46:10 volumio volumio[873]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:46:10 volumio volumio[873]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 07 14:46:10 volumio volumio[873]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 07 14:46:10 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:10 volumio volumio[873]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:46:10 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:10 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:10 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:10 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3787,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:10 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:10 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:10 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:10 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:10 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:10 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:10 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:46:10 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:10 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:10 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:10 volumio volumio[873]: info: ControllerMpd::pushState Sep 07 14:46:10 volumio volumio[873]: info: CoreCommandRouter::servicePushState Sep 07 14:46:10 volumio volumio[873]: info: CorePlayQueue::getTrack 0 Sep 07 14:46:10 volumio volumio[873]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3787,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nick Straker Band - A walk in the park (1979)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Sep 07 14:46:10 volumio volumio[873]: verbose: CURRENT POSITION 0 Sep 07 14:46:10 volumio volumio[873]: info: CoreStateMachine::syncState stateService play Sep 07 14:46:10 volumio volumio[873]: info: CoreStateMachine::syncState currentStatus play Sep 07 14:46:10 volumio volumio[873]: info: Received an update from plugin. extracting info from payload Sep 07 14:46:10 volumio volumio[873]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 07 14:46:10 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:10 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:10 volumio volumio[873]: info: CoreStateMachine::pushState Sep 07 14:46:10 volumio volumio[873]: info: CoreCommandRouter::volumioPushState Sep 07 14:46:10 volumio volumio[873]: info: ------------------------------ 26ms Sep 07 14:46:10 volumio volumio[873]: info: ------------------------------ 25ms Sep 07 14:46:16 volumio volumio[873]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 14:46:16 volumio volumio[873]: Error: socket hang up Sep 07 14:46:16 volumio volumio[873]: at connResetException (internal/errors.js:607:14) Sep 07 14:46:16 volumio volumio[873]: at Socket.socketOnEnd (_http_client.js:493:23) Sep 07 14:46:16 volumio volumio[873]: at Socket.emit (events.js:327:22) Sep 07 14:46:16 volumio volumio[873]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 07 14:46:16 volumio volumio[873]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 07 14:46:16 volumio volumio[873]: code: 'ECONNRESET' Sep 07 14:46:16 volumio volumio[873]: } Sep 07 14:46:16 volumio volumio[873]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 14:46:16 volumio sudo[3390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-07 14:45 Sep 07 14:46:16 volumio sudo[3390]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"