Apr 02 23:09:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:02 volumio volumio[15884]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 02 23:09:02 volumio volumio[15884]: info: Preparing to save Alsa Options, stopping services first Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::volumioGetState Apr 02 23:09:02 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::volumioPause Apr 02 23:09:02 volumio volumio[15884]: info: CoreStateMachine::pause Apr 02 23:09:02 volumio volumio[15884]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 23:09:02 volumio volumio[15884]: info: Found match in Cards Database: setting mixer PCM for card Headphones Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 02 23:09:02 volumio volumio[15884]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 02 23:09:02 volumio volumio[15884]: info: Disabling external Volume Control Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 23:09:02 volumio volumio[15884]: info: Preparing to generate the ALSA configuration file Apr 02 23:09:02 volumio volumio[15884]: info: VolumeController:: Volume=86 Mute =false Apr 02 23:09:02 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:02 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 02 23:09:02 volumio volumio[15884]: info: Asound.conf file written Apr 02 23:09:02 volumio sudo[16736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 02 23:09:02 volumio sudo[16736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:09:02 volumio sudo[16736]: pam_unix(sudo:session): session closed for user root Apr 02 23:09:02 volumio volumio[15884]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 02 23:09:02 volumio volumio[15884]: No state is present for card b1 Apr 02 23:09:02 volumio volumio[15884]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 02 23:09:02 volumio volumio[15884]: /usr/share/alsa/init/bcm2835:14: control element not found Apr 02 23:09:02 volumio volumio[15884]: No state is present for card b1 Apr 02 23:09:02 volumio volumio[15884]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 02 23:09:02 volumio volumio[15884]: No state is present for card Headphones Apr 02 23:09:02 volumio volumio[15884]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 02 23:09:02 volumio volumio[15884]: /usr/share/alsa/init/bcm2835:20: control element not found Apr 02 23:09:02 volumio volumio[15884]: No state is present for card Headphones Apr 02 23:09:02 volumio volumio[15884]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 02 23:09:02 volumio volumio[15884]: No state is present for card vc4hdmi Apr 02 23:09:02 volumio volumio[15884]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 02 23:09:02 volumio volumio[15884]: Found hardware: "vc4-hdmi" "" "" "" "" Apr 02 23:09:02 volumio volumio[15884]: Hardware is initialized using a generic method Apr 02 23:09:02 volumio volumio[15884]: No state is present for card vc4hdmi Apr 02 23:09:02 volumio volumio[15884]: info: Output device has changed, restarting MPD Apr 02 23:09:02 volumio volumio[15884]: info: Output device has changed, restarting Shairport Sync Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:09:02 volumio sudo[16742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 02 23:09:02 volumio sudo[16742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:09:02 volumio sudo[16744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 02 23:09:02 volumio sudo[16744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:09:02 volumio sudo[16742]: pam_unix(sudo:session): session closed for user root Apr 02 23:09:02 volumio volumio[15884]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 02 23:09:02 volumio volumio[15884]: info: Output device has changed, restarting MPD Apr 02 23:09:02 volumio sudo[16752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 02 23:09:02 volumio sudo[16752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:09:02 volumio sudo[16752]: pam_unix(sudo:session): session closed for user root Apr 02 23:09:02 volumio volumio[15884]: info: Output device has changed, restarting Shairport Sync Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:09:02 volumio sudo[16756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 02 23:09:02 volumio sudo[16756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:09:02 volumio volumio[15884]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 02 23:09:02 volumio volumio[15884]: info: MPD Permissions set Apr 02 23:09:02 volumio volumio[15884]: info: MPD Permissions set Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:02 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:09:02 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:03 volumio volumio[15884]: info: Starting Shairport Sync Apr 02 23:09:03 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 02 23:09:03 volumio volumio[15884]: info: Starting Shairport Sync Apr 02 23:09:03 volumio sudo[16781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 02 23:09:03 volumio sudo[16781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:09:03 volumio sudo[16784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 02 23:09:03 volumio sudo[16784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:09:03 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 02 23:09:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 02 23:09:03 volumio systemd[1]: mpd.service: Consumed 4.342s CPU time. Apr 02 23:09:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 02 23:09:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 02 23:09:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 02 23:09:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 02 23:09:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 02 23:09:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 02 23:09:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 02 23:09:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 02 23:09:03 volumio systemd[1]: shairport-sync.service: Consumed 2.814s CPU time. Apr 02 23:09:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 02 23:09:03 volumio sudo[16781]: pam_unix(sudo:session): session closed for user root Apr 02 23:09:03 volumio sudo[16784]: pam_unix(sudo:session): session closed for user root Apr 02 23:09:03 volumio volumio[15884]: info: Shairport-Sync Started Apr 02 23:09:03 volumio volumio[15884]: info: Shairport-Sync Started Apr 02 23:09:03 volumio sudo[16795]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 02 23:09:03 volumio sudo[16795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 02 23:09:03 volumio sudo[16795]: pam_unix(sudo:session): session closed for user root Apr 02 23:09:05 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:05 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:05 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:05 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:05 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:05 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:06 volumio mpd[16814]: 2026-04-02T23:09:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 02 23:09:06 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 02 23:09:06 volumio sudo[16756]: pam_unix(sudo:session): session closed for user root Apr 02 23:09:06 volumio sudo[16744]: pam_unix(sudo:session): session closed for user root Apr 02 23:09:06 volumio volumio[15884]: error: MPD error: The expression evaluated to a falsy value: Apr 02 23:09:06 volumio volumio[15884]: assert.ok(self.idling) Apr 02 23:09:06 volumio volumio[15884]: error: The expression evaluated to a falsy value: Apr 02 23:09:06 volumio volumio[15884]: assert.ok(self.idling) Apr 02 23:09:06 volumio volumio[15884]: error: updateQueue error: null Apr 02 23:09:06 volumio volumio[15884]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 02 23:09:06 volumio volumio[15884]: info: CoreStateMachine::getcurrentVolume Apr 02 23:09:06 volumio volumio[15884]: info: CoreCommandRouter::volumioRetrievevolume Apr 02 23:09:06 volumio volumio[15884]: info: VolumeController:: Volume=86 Mute =false Apr 02 23:09:06 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:06 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:06 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:09:06 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:06 volumio volumio[15884]: info: CoreStateMachine::updateTrackBlock Apr 02 23:09:06 volumio volumio[15884]: info: CorePlayQueue::getTrackBlock Apr 02 23:09:06 volumio volumio[15884]: info: CoreCommandRouter::volumioRetrievevolume Apr 02 23:09:06 volumio volumio[15884]: info: VolumeController:: Volume=86 Mute =false Apr 02 23:09:06 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:06 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:06 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:09:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:09 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:09 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:09 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:09 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:09 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:09 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:10 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:10 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:10 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:10 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:10 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:10 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:11 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:11 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:11 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:11 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:11 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:11 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:12 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:12 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:12 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:12 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:12 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:12 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:13 volumio volumio[15884]: info: CoreCommandRouter::volumioGetState Apr 02 23:09:13 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:13 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:13 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:13 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:13 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:13 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:13 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:14 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:14 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:14 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:14 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:14 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:14 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::volumioPlay Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::play index undefined Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::startPlaybackTimer Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::clearAddPlayTracks USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3 Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand stop took 1 milliseconds Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 23:09:14 volumio volumio[15884]: info: Apr 02 23:09:14 volumio volumio[15884]: ---------------------------- MPD announces system playlist update Apr 02 23:09:14 volumio volumio[15884]: info: Ignoring MPD Status Update Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand clear took 2 milliseconds Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand add "USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3" Apr 02 23:09:14 volumio volumio[15884]: info: Apr 02 23:09:14 volumio volumio[15884]: ---------------------------- MPD announces system playlist update Apr 02 23:09:14 volumio volumio[15884]: info: Ignoring MPD Status Update Apr 02 23:09:14 volumio volumio[15884]: error: updateQueue error: null Apr 02 23:09:14 volumio volumio[15884]: info: Apr 02 23:09:14 volumio volumio[15884]: ---------------------------- MPD announces system playlist update Apr 02 23:09:14 volumio volumio[15884]: info: Ignoring MPD Status Update Apr 02 23:09:14 volumio volumio[15884]: info: ------------------------------ 6ms Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand add "USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3" took 5 milliseconds Apr 02 23:09:14 volumio volumio[15884]: info: ------------------------------ 4ms Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand play Apr 02 23:09:14 volumio volumio[15884]: info: Apr 02 23:09:14 volumio volumio[15884]: ---------------------------- MPD announces system playlist update Apr 02 23:09:14 volumio volumio[15884]: info: Ignoring MPD Status Update Apr 02 23:09:14 volumio volumio[15884]: info: ------------------------------ 6ms Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand play took 4 milliseconds Apr 02 23:09:14 volumio volumio[15884]: info: ------------------------------ 4ms Apr 02 23:09:14 volumio volumio[15884]: info: Apr 02 23:09:14 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:09:14 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:09:14 volumio volumio[15884]: info: Apr 02 23:09:14 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:09:14 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:09:14 volumio volumio[15884]: info: Apr 02 23:09:14 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand status took 7 milliseconds Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand status took 7 milliseconds Apr 02 23:09:14 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:09:14 volumio volumio[15884]: info: Apr 02 23:09:14 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:09:14 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand status took 7 milliseconds Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:09:14 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:09:14 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::syncState stateService play Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus stop Apr 02 23:09:14 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":596,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:09:14 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::syncState stateService play Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus play Apr 02 23:09:14 volumio volumio[15884]: info: Received an update from plugin. extracting info from payload Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:14 volumio volumio[15884]: info: ------------------------------ 37ms Apr 02 23:09:14 volumio volumio[15884]: info: ------------------------------ 55ms Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand status took 43 milliseconds Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 40 milliseconds Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:09:14 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:09:14 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::syncState stateService play Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus play Apr 02 23:09:14 volumio volumio[15884]: info: Received an update from plugin. extracting info from payload Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:14 volumio volumio[15884]: info: ------------------------------ 81ms Apr 02 23:09:14 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 55 milliseconds Apr 02 23:09:14 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:09:14 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:09:14 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::syncState stateService play Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus play Apr 02 23:09:14 volumio volumio[15884]: info: Received an update from plugin. extracting info from payload Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:14 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:14 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:14 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:14 volumio volumio[15884]: info: ------------------------------ 132ms Apr 02 23:09:15 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:15 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:15 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:15 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:15 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:15 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:17 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:17 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:17 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:17 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:17 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:17 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:21 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:21 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:21 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:21 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:21 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:21 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:22 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:22 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:22 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:22 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:22 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:22 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:26 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:26 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:26 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:26 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:26 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:26 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:28 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:28 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:28 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:28 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:28 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:28 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:29 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:29 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:29 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:29 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:29 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:29 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:31 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:31 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:31 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:31 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:31 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:31 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:33 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:33 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:33 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:33 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:33 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:33 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:34 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:34 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:34 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:34 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:34 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:34 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:35 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:35 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:35 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:35 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:35 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:35 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:36 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:36 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:36 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:36 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:36 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:36 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:38 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:38 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:38 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:38 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:38 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:38 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioSeek Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::seek Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: TRACKBLOCK {"uri":"music-library/USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","service":"mpd","name":"起风了","artist":"买辣椒也用券","album":"起风了","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=306&web=%E4%B9%B0%E8%BE%A3%E6%A4%92%E4%B9%9F%E7%94%A8%E5%88%B8/%E8%B5%B7%E9%A3%8E%E4%BA%86/extralarge&path=%2Fmnt%2FUSB%2FDA7F-4D31%2FMUSIC&metadata=false","duration":326,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::startPlaybackTimer Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::seek Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: error: null Apr 02 23:09:39 volumio volumio[15884]: info: Apr 02 23:09:39 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:09:39 volumio volumio[15884]: info: sendMpdCommand status took 2 milliseconds Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:09:39 volumio volumio[15884]: info: Apr 02 23:09:39 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:09:39 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143000,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:09:39 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::syncState stateService play Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus play Apr 02 23:09:39 volumio volumio[15884]: info: Received an update from plugin. extracting info from payload Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: info: ------------------------------ 34ms Apr 02 23:09:39 volumio volumio[15884]: info: sendMpdCommand status took 30 milliseconds Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:09:39 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143000,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:09:39 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::syncState stateService play Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus play Apr 02 23:09:39 volumio volumio[15884]: info: Received an update from plugin. extracting info from payload Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: info: ------------------------------ 72ms Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioSeek Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::seek Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: TRACKBLOCK {"uri":"music-library/USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","service":"mpd","name":"起风了","artist":"买辣椒也用券","album":"起风了","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=306&web=%E4%B9%B0%E8%BE%A3%E6%A4%92%E4%B9%9F%E7%94%A8%E5%88%B8/%E8%B5%B7%E9%A3%8E%E4%BA%86/extralarge&path=%2Fmnt%2FUSB%2FDA7F-4D31%2FMUSIC&metadata=false","duration":326,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::startPlaybackTimer Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::seek Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: error: null Apr 02 23:09:39 volumio volumio[15884]: info: Apr 02 23:09:39 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:09:39 volumio volumio[15884]: info: Apr 02 23:09:39 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:09:39 volumio volumio[15884]: info: sendMpdCommand status took 9 milliseconds Apr 02 23:09:39 volumio volumio[15884]: info: sendMpdCommand status took 7 milliseconds Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:09:39 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 23:09:39 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:09:39 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143000,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:09:39 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::syncState stateService play Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus play Apr 02 23:09:39 volumio volumio[15884]: info: Received an update from plugin. extracting info from payload Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143000,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:09:39 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::syncState stateService play Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus play Apr 02 23:09:39 volumio volumio[15884]: info: Received an update from plugin. extracting info from payload Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:09:39 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:09:39 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:09:39 volumio volumio[15884]: info: ------------------------------ 64ms Apr 02 23:09:39 volumio volumio[15884]: info: ------------------------------ 63ms Apr 02 23:09:40 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:40 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:40 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:40 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:40 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:40 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:40 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 02 23:09:40 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 02 23:09:40 volumio systemd[1]: setdatetime-helper.service: Consumed 1.616s CPU time. Apr 02 23:09:42 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:42 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:42 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:42 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:42 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:42 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:43 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:43 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:43 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:43 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:43 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:43 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:45 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:45 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:45 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:45 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:45 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:45 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:47 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:47 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:47 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:47 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:47 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:47 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:48 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:48 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:48 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:48 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:48 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:48 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:53 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:53 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:53 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:53 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:53 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:53 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:54 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:54 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:54 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:54 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:54 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:54 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:55 volumio volumio[15884]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 23:09:55 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 02 23:09:55 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:09:55 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 23:09:56 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:56 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:56 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:56 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:56 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:56 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:56 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:09:56 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 02 23:09:56 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 02 23:09:57 volumio volumio[15884]: error: error Apr 02 23:09:58 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:58 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:58 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:58 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:58 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:09:58 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:01 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:02 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:02 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:02 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:02 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:02 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:02 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:04 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:04 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:04 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:04 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:04 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:04 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:06 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:06 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:06 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:06 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:06 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:06 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:07 volumio volumio[15884]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 02 23:10:07 volumio volumio[15884]: info: Preparing to save Alsa Options, stopping services first Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::volumioGetState Apr 02 23:10:07 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::volumioPause Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::pause Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::stPlaybackTimer Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::servicePause Apr 02 23:10:07 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::servicePause Apr 02 23:10:07 volumio volumio[15884]: info: ControllerMpd::pause Apr 02 23:10:07 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand pause Apr 02 23:10:07 volumio volumio[15884]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 23:10:07 volumio volumio[15884]: info: Enabling PI HDMI Output Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 23:10:07 volumio volumio[15884]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 02 23:10:07 volumio volumio[15884]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 02 23:10:07 volumio volumio[15884]: info: Disabling external Volume Control Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 23:10:07 volumio volumio[15884]: info: Preparing to generate the ALSA configuration file Apr 02 23:10:07 volumio volumio[15884]: info: Apr 02 23:10:07 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:10:07 volumio volumio[15884]: info: sendMpdCommand pause took 122 milliseconds Apr 02 23:10:07 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:10:07 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:10:07 volumio volumio[15884]: info: Apr 02 23:10:07 volumio volumio[15884]: ---------------------------- MPD announces state update: player Apr 02 23:10:07 volumio volumio[15884]: info: ControllerMpd::getState Apr 02 23:10:07 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand status Apr 02 23:10:07 volumio volumio[15884]: info: Raspberry PI HDMI Output Enabled Apr 02 23:10:07 volumio volumio[15884]: info: sendMpdCommand status took 26 milliseconds Apr 02 23:10:07 volumio volumio[15884]: info: sendMpdCommand status took 24 milliseconds Apr 02 23:10:07 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:10:07 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:10:07 volumio volumio[15884]: verbose: ControllerMpd::parseState Apr 02 23:10:07 volumio volumio[15884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 23:10:07 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 23:10:07 volumio volumio[15884]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 02 23:10:07 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:10:07 volumio volumio[15884]: verbose: ControllerMpd::parseTrackInfo Apr 02 23:10:07 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:10:07 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:10:07 volumio volumio[15884]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":171335,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:10:07 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::syncState stateService pause Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus pause Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:10:07 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::stPlaybackTimer Apr 02 23:10:07 volumio volumio[15884]: info: ControllerMpd::pushState Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::servicePushState Apr 02 23:10:07 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:10:07 volumio volumio[15884]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":171335,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"起风了","artist":"买辣椒也用券","album":"起风了","uri":"USB/DA7F-4D31/MUSIC/买辣椒也用券 - 起风了.mp3","trackType":"mp3"} Apr 02 23:10:07 volumio volumio[15884]: verbose: CURRENT POSITION 0 Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::syncState stateService pause Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::syncState currentStatus pause Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:10:07 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::stPlaybackTimer Apr 02 23:10:07 volumio sudo[16971]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store Apr 02 23:10:07 volumio sudo[16971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:10:07 volumio volumio[15884]: info: ------------------------------ 65ms Apr 02 23:10:07 volumio volumio[15884]: info: ------------------------------ 63ms Apr 02 23:10:07 volumio volumio[15884]: info: VolumeController:: Volume=100 Mute =false Apr 02 23:10:07 volumio volumio[15884]: info: CoreStateMachine::pushState Apr 02 23:10:07 volumio volumio[15884]: info: CorePlayQueue::getTrack 0 Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::volumioPushState Apr 02 23:10:07 volumio sudo[16971]: pam_unix(sudo:session): session closed for user root Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 02 23:10:07 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:07 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:07 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:07 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:07 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:07 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:07 volumio volumio[15884]: info: Alsa Settings successfully stored Apr 02 23:10:07 volumio volumio[15884]: info: Asound.conf file written Apr 02 23:10:07 volumio sudo[16984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 02 23:10:07 volumio sudo[16984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:10:07 volumio sudo[16984]: pam_unix(sudo:session): session closed for user root Apr 02 23:10:07 volumio volumio[15884]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 02 23:10:07 volumio volumio[15884]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 02 23:10:07 volumio volumio[15884]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 02 23:10:07 volumio volumio[15884]: info: Output device has changed, restarting MPD Apr 02 23:10:07 volumio volumio[15884]: info: Output device has changed, restarting Shairport Sync Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:10:07 volumio sudo[16990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 02 23:10:07 volumio sudo[16990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:10:07 volumio sudo[16992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 02 23:10:07 volumio sudo[16992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:10:07 volumio sudo[16990]: pam_unix(sudo:session): session closed for user root Apr 02 23:10:07 volumio volumio[15884]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 02 23:10:07 volumio volumio[15884]: info: Output device has changed, restarting MPD Apr 02 23:10:07 volumio volumio[15884]: info: Output device has changed, restarting Shairport Sync Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:10:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 02 23:10:07 volumio sudo[17000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 02 23:10:07 volumio sudo[17000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:10:07 volumio sudo[17000]: pam_unix(sudo:session): session closed for user root Apr 02 23:10:07 volumio volumio[15884]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 02 23:10:07 volumio sudo[17003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 02 23:10:07 volumio sudo[17003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:10:07 volumio volumio[15884]: info: MPD Permissions set Apr 02 23:10:07 volumio volumio[15884]: info: MPD Permissions set Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 02 23:10:07 volumio systemd[1]: mpd.service: Consumed 4.707s CPU time. Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 02 23:10:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 02 23:10:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 23:10:07 volumio volumio[15884]: info: Starting Shairport Sync Apr 02 23:10:07 volumio volumio[15884]: info: Starting Shairport Sync Apr 02 23:10:07 volumio sudo[17022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 02 23:10:07 volumio sudo[17022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:10:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 02 23:10:07 volumio sudo[17025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 02 23:10:07 volumio sudo[17025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 02 23:10:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 02 23:10:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 02 23:10:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 02 23:10:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 02 23:10:07 volumio systemd[1]: shairport-sync.service: Consumed 1.846s CPU time. Apr 02 23:10:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 02 23:10:07 volumio sudo[17022]: pam_unix(sudo:session): session closed for user root Apr 02 23:10:07 volumio sudo[17025]: pam_unix(sudo:session): session closed for user root Apr 02 23:10:07 volumio volumio[15884]: info: Shairport-Sync Started Apr 02 23:10:07 volumio volumio[15884]: info: Shairport-Sync Started Apr 02 23:10:08 volumio sudo[17027]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 02 23:10:08 volumio sudo[17027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 02 23:10:08 volumio sudo[17027]: pam_unix(sudo:session): session closed for user root Apr 02 23:10:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:08 volumio volumio-remote-updater[782]: PROGRESS: 85, STATUS: "Downloading new update", ETA: "2m" Apr 02 23:10:08 volumio volumio[15884]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 23:10:08 volumio volumio[15884]: Error: connect ETIMEDOUT 157.240.8.50:80 Apr 02 23:10:08 volumio volumio[15884]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Apr 02 23:10:08 volumio volumio[15884]: errno: -110, Apr 02 23:10:08 volumio volumio[15884]: code: 'ETIMEDOUT', Apr 02 23:10:08 volumio volumio[15884]: syscall: 'connect', Apr 02 23:10:08 volumio volumio[15884]: address: '157.240.8.50', Apr 02 23:10:08 volumio volumio[15884]: port: 80 Apr 02 23:10:08 volumio volumio[15884]: } Apr 02 23:10:08 volumio volumio[15884]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 23:10:09 volumio sudo[17060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-02 23:09' Apr 02 23:10:09 volumio sudo[17060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"