-- Logs begin at Wed 2025-04-16 04:21:14 UTC, end at Wed 2025-04-16 04:39:37 UTC. -- Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 16 04:38:13 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 16 04:38:19 volumio volumio[3208]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 16 04:38:19 volumio volumio[3208]: info: Preparing to save Alsa Options, stopping services first Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::volumioGetState Apr 16 04:38:19 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::volumioPause Apr 16 04:38:19 volumio volumio[3208]: info: CoreStateMachine::pause Apr 16 04:38:19 volumio volumio[3208]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 04:38:19 volumio volumio[3208]: info: Found match in Cards Database: setting mixer for card Analog Out Apr 16 04:38:19 volumio volumio[3208]: info: Setting mixer Master for card Analog Out Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 16 04:38:19 volumio volumio[3208]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 16 04:38:19 volumio volumio[3208]: info: Disabling external Volume Control Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 04:38:19 volumio volumio[3208]: info: Preparing to generate the ALSA configuration file Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 16 04:38:19 volumio volumio[3208]: info: VolumeController:: Volume=100 Mute =false Apr 16 04:38:19 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:19 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:19 volumio volumio[3208]: info: Asound.conf file written Apr 16 04:38:19 volumio sudo[4809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 16 04:38:19 volumio sudo[4809]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:19 volumio sudo[4809]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:19 volumio volumio[3208]: info: Output device has changed, restarting MPD Apr 16 04:38:19 volumio sudo[4815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 04:38:19 volumio sudo[4815]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:19 volumio sudo[4815]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:19 volumio volumio[3208]: info: Output device has changed, restarting Shairport Sync Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:19 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 04:38:19 volumio sudo[4818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 04:38:19 volumio sudo[4818]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:19 volumio systemd[1]: Stopping Music Player Daemon... Apr 16 04:38:19 volumio volumio[3208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 04:38:19 volumio volumio[3208]: info: Output device has changed, restarting MPD Apr 16 04:38:19 volumio systemd[1]: mpd.service: Succeeded. Apr 16 04:38:19 volumio systemd[1]: Stopped Music Player Daemon. Apr 16 04:38:20 volumio systemd[1]: Starting Music Player Daemon... Apr 16 04:38:20 volumio sudo[4827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 04:38:20 volumio volumio[3208]: info: Output device has changed, restarting Shairport Sync Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 04:38:20 volumio sudo[4827]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:20 volumio sudo[4829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 04:38:20 volumio sudo[4829]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:20 volumio sudo[4827]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:20 volumio volumio[3208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 04:38:20 volumio volumio[3208]: info: MPD Permissions set Apr 16 04:38:20 volumio volumio[3208]: info: MPD Permissions set Apr 16 04:38:20 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 16 04:38:20 volumio systemd[1]: mpd.service: Succeeded. Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio systemd[1]: Stopped Music Player Daemon. Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 16 04:38:20 volumio systemd[1]: Starting Music Player Daemon... Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 16 04:38:20 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:20 volumio volumio[3208]: info: Starting Shairport Sync Apr 16 04:38:20 volumio sudo[4835]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 16 04:38:20 volumio sudo[4835]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:20 volumio sudo[4835]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:20 volumio volumio[3208]: info: Starting Shairport Sync Apr 16 04:38:20 volumio sudo[4851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 04:38:20 volumio sudo[4851]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:20 volumio sudo[4854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 04:38:20 volumio sudo[4854]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 16 04:38:20 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 16 04:38:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 16 04:38:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 16 04:38:20 volumio sudo[4851]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:20 volumio volumio[3208]: info: Shairport-Sync Started Apr 16 04:38:20 volumio sudo[4854]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:20 volumio volumio[3208]: info: Shairport-Sync Started Apr 16 04:38:20 volumio mpd[4850]: Apr 16 04:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 04:38:20 volumio systemd[1]: Started Music Player Daemon. Apr 16 04:38:20 volumio sudo[4818]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:20 volumio sudo[4829]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:20 volumio volumio[3208]: error: MPD error: The expression evaluated to a falsy value: Apr 16 04:38:20 volumio volumio[3208]: assert.ok(self.idling) Apr 16 04:38:20 volumio volumio[3208]: error: The expression evaluated to a falsy value: Apr 16 04:38:20 volumio volumio[3208]: assert.ok(self.idling) Apr 16 04:38:20 volumio volumio[3208]: error: updateQueue error: null Apr 16 04:38:23 volumio volumio[3208]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 16 04:38:23 volumio volumio[3208]: info: CoreStateMachine::getcurrentVolume Apr 16 04:38:23 volumio volumio[3208]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 04:38:23 volumio volumio[3208]: info: VolumeController:: Volume=77 Mute =false Apr 16 04:38:23 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:23 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:23 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:23 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:23 volumio volumio[3208]: info: CoreStateMachine::updateTrackBlock Apr 16 04:38:23 volumio volumio[3208]: info: CorePlayQueue::getTrackBlock Apr 16 04:38:23 volumio volumio[3208]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 04:38:23 volumio volumio[3208]: info: VolumeController:: Volume=77 Mute =false Apr 16 04:38:23 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:23 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:23 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:23 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:24 volumio volumio[3208]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Apr 16 04:38:24 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Apr 16 04:38:24 volumio sudo[4867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 04:38:24 volumio sudo[4867]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:24 volumio sudo[4867]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:24 volumio volumio[3208]: info: MPD Permissions set Apr 16 04:38:24 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:24 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:24 volumio sudo[4870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 04:38:24 volumio sudo[4870]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:24 volumio systemd[1]: Stopping Music Player Daemon... Apr 16 04:38:24 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 04:38:24 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 16 04:38:24 volumio systemd[1]: mpd.service: Succeeded. Apr 16 04:38:24 volumio systemd[1]: Stopped Music Player Daemon. Apr 16 04:38:24 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:24 volumio systemd[1]: Starting Music Player Daemon... Apr 16 04:38:24 volumio sudo[4876]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 16 04:38:24 volumio sudo[4876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:24 volumio sudo[4876]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:25 volumio mpd[4880]: Apr 16 04:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 04:38:25 volumio systemd[1]: Started Music Player Daemon. Apr 16 04:38:25 volumio sudo[4870]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:25 volumio volumio[3208]: error: updateQueue error: null Apr 16 04:38:27 volumio volumio[3208]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Apr 16 04:38:27 volumio sudo[4884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 04:38:27 volumio sudo[4884]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:27 volumio sudo[4884]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:27 volumio volumio[3208]: info: MPD Permissions set Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:27 volumio sudo[4887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 04:38:27 volumio sudo[4887]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:27 volumio systemd[1]: Stopping Music Player Daemon... Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 16 04:38:27 volumio systemd[1]: mpd.service: Succeeded. Apr 16 04:38:27 volumio systemd[1]: Stopped Music Player Daemon. Apr 16 04:38:27 volumio systemd[1]: Starting Music Player Daemon... Apr 16 04:38:27 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 04:38:27 volumio sudo[4893]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 16 04:38:27 volumio sudo[4893]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 04:38:27 volumio sudo[4893]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:28 volumio mpd[4897]: Apr 16 04:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 04:38:28 volumio systemd[1]: Started Music Player Daemon. Apr 16 04:38:28 volumio sudo[4887]: pam_unix(sudo:session): session closed for user root Apr 16 04:38:28 volumio volumio[3208]: error: updateQueue error: null Apr 16 04:38:42 volumio volumio[3208]: info: CoreCommandRouter::volumioPlay Apr 16 04:38:42 volumio volumio[3208]: info: CoreStateMachine::play index undefined Apr 16 04:38:42 volumio volumio[3208]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 04:38:42 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:42 volumio volumio[3208]: info: CoreStateMachine::startPlaybackTimer Apr 16 04:38:42 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::clearAddPlayTracks USB/大白菜U盘/38 郑中基 DSD 12首/郑中基 - My Only One.dsf Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 04:38:42 volumio volumio[3208]: info: sendMpdCommand stop took 1 milliseconds Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 04:38:42 volumio volumio[3208]: info: Apr 16 04:38:42 volumio volumio[3208]: ---------------------------- MPD announces system playlist update Apr 16 04:38:42 volumio volumio[3208]: info: Ignoring MPD Status Update Apr 16 04:38:42 volumio volumio[3208]: info: sendMpdCommand clear took 1 milliseconds Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::sendMpdCommand add "USB/大白菜U盘/38 郑中基 DSD 12首/郑中基 - My Only One.dsf" Apr 16 04:38:42 volumio volumio[3208]: error: updateQueue error: null Apr 16 04:38:42 volumio volumio[3208]: info: Apr 16 04:38:42 volumio volumio[3208]: ---------------------------- MPD announces system playlist update Apr 16 04:38:42 volumio volumio[3208]: info: Ignoring MPD Status Update Apr 16 04:38:42 volumio volumio[3208]: info: ------------------------------ 3ms Apr 16 04:38:42 volumio volumio[3208]: info: sendMpdCommand add "USB/大白菜U盘/38 郑中基 DSD 12首/郑中基 - My Only One.dsf" took 2 milliseconds Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::sendMpdCommand play Apr 16 04:38:42 volumio volumio[3208]: info: ------------------------------ 2ms Apr 16 04:38:42 volumio volumio[3208]: info: sendMpdCommand play took 2 milliseconds Apr 16 04:38:42 volumio volumio[3208]: info: Apr 16 04:38:42 volumio volumio[3208]: ---------------------------- MPD announces state update: player Apr 16 04:38:42 volumio volumio[3208]: info: ControllerMpd::getState Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::sendMpdCommand status Apr 16 04:38:42 volumio volumio[3208]: info: Apr 16 04:38:42 volumio volumio[3208]: ---------------------------- MPD announces state update: player Apr 16 04:38:42 volumio volumio[3208]: info: sendMpdCommand status took 36 milliseconds Apr 16 04:38:42 volumio volumio[3208]: info: ControllerMpd::getState Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::sendMpdCommand status Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::parseState Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 04:38:42 volumio volumio[3208]: info: sendMpdCommand status took 23 milliseconds Apr 16 04:38:42 volumio volumio[3208]: info: sendMpdCommand playlistinfo took 22 milliseconds Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::parseState Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::parseTrackInfo Apr 16 04:38:42 volumio volumio[3208]: info: ControllerMpd::pushState Apr 16 04:38:42 volumio volumio[3208]: info: CoreCommandRouter::servicePushState Apr 16 04:38:42 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:42 volumio volumio[3208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"郑中基 - My Only One","artist":"郑中基","album":null,"uri":"USB/大白菜U盘/38 郑中基 DSD 12首/郑中基 - My Only One.dsf","trackType":"dsf"} Apr 16 04:38:42 volumio volumio[3208]: verbose: CURRENT POSITION 0 Apr 16 04:38:42 volumio volumio[3208]: info: CoreStateMachine::syncState stateService play Apr 16 04:38:42 volumio volumio[3208]: info: CoreStateMachine::syncState currentStatus stop Apr 16 04:38:42 volumio volumio[3208]: info: ------------------------------ 62ms Apr 16 04:38:42 volumio volumio[3208]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 04:38:42 volumio volumio[3208]: verbose: ControllerMpd::parseTrackInfo Apr 16 04:38:42 volumio volumio[3208]: info: ControllerMpd::pushState Apr 16 04:38:42 volumio volumio[3208]: info: CoreCommandRouter::servicePushState Apr 16 04:38:42 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:42 volumio volumio[3208]: verbose: STATE SERVICE {"status":"play","position":0,"seek":739,"duration":199,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"郑中基 - My Only One","artist":"郑中基","album":null,"uri":"USB/大白菜U盘/38 郑中基 DSD 12首/郑中基 - My Only One.dsf","trackType":"dsf"} Apr 16 04:38:42 volumio volumio[3208]: verbose: CURRENT POSITION 0 Apr 16 04:38:42 volumio volumio[3208]: info: CoreStateMachine::syncState stateService play Apr 16 04:38:42 volumio volumio[3208]: info: CoreStateMachine::syncState currentStatus play Apr 16 04:38:42 volumio volumio[3208]: info: Received an update from plugin. extracting info from payload Apr 16 04:38:42 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:42 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:42 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:42 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:42 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:42 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:42 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:42 volumio volumio[3208]: info: ------------------------------ 56ms Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 80 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume80 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: Done setting volume on: Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 84 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume84 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 86 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume86 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: Done setting volume on: Apr 16 04:38:51 volumio volumio[3208]: info: Done setting volume on: Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 87 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume87 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 88 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume88 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 90 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume90 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 91 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume91 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: Done setting volume on: Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 93 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume93 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: Done setting volume on: Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 94 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume94 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 94 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume94 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: Done setting volume on: Apr 16 04:38:51 volumio volumio[3208]: info: Done setting volume on: Apr 16 04:38:51 volumio volumio[3208]: info: Done setting volume on: Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Apr 16 04:38:51 volumio volumio[3208]: info: Setting Remote Device Volume: http://192.168.2.106 Apr 16 04:38:51 volumio volumio[3208]: info: Apr 16 04:38:51 volumio volumio[3208]: ---------------------------- Client requests Volume 95 Apr 16 04:38:51 volumio volumio[3208]: info: VolumeController::SetAlsaVolume95 Apr 16 04:38:51 volumio volumio[3208]: info: CoreStateMachine::pushState Apr 16 04:38:51 volumio volumio[3208]: info: CorePlayQueue::getTrack 0 Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 04:38:51 volumio volumio[3208]: info: CoreCommandRouter::volumioPushState Apr 16 04:38:51 volumio volumio[3208]: info: Done setting volume on: Apr 16 04:39:37 volumio volumio[3208]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 04:39:37 volumio volumio[3208]: Error: connect ETIMEDOUT 31.13.87.33:80 Apr 16 04:39:37 volumio volumio[3208]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 16 04:39:37 volumio volumio[3208]: errno: -110, Apr 16 04:39:37 volumio volumio[3208]: code: 'ETIMEDOUT', Apr 16 04:39:37 volumio volumio[3208]: syscall: 'connect', Apr 16 04:39:37 volumio volumio[3208]: address: '31.13.87.33', Apr 16 04:39:37 volumio volumio[3208]: port: 80 Apr 16 04:39:37 volumio volumio[3208]: } Apr 16 04:39:37 volumio volumio[3208]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 04:39:37 volumio sudo[5080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-16 04:38 Apr 16 04:39:37 volumio sudo[5080]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"