-- Logs begin at Tue 2025-06-24 16:25:54 UTC, end at Tue 2025-06-24 16:32:57 UTC. -- Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 24 16:31:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 24 16:31:57 volumio ntpd[1128]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 24 16:32:18 volumio volumio[849]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::volumioGetState Jun 24 16:32:18 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:18 volumio volumio[849]: info: Restoring Previous Volume level: 4 false true Jun 24 16:32:18 volumio volumio[849]: info: VolumeController::SetAlsaVolume100 Jun 24 16:32:18 volumio volumio[849]: info: Enable softmixer device for audio device number 5 Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::volumioStop Jun 24 16:32:18 volumio volumio[849]: info: CoreStateMachine::stop Jun 24 16:32:18 volumio volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:32:18 volumio volumio[849]: info: CoreStateMachine::stPlaybackTimer Jun 24 16:32:18 volumio volumio[849]: info: CoreStateMachine::updateTrackBlock Jun 24 16:32:18 volumio volumio[849]: info: CorePlayQueue::getTrackBlock Jun 24 16:32:18 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:18 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:18 volumio volumio[849]: info: CoreStateMachine::serviceStop Jun 24 16:32:18 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::serviceStop Jun 24 16:32:18 volumio volumio[849]: info: ControllerMpd::stop Jun 24 16:32:18 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:32:18 volumio volumio[849]: info: Enable softmixer device for audio device undefined Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 24 16:32:18 volumio volumio[849]: info: Updating Volume Controller Parameters: Device: 5 Name: Serenade DSD Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 24 16:32:18 volumio volumio[849]: info: Disabling external Volume Control Jun 24 16:32:18 volumio volumio[849]: info: Output device has changed, restarting MPD Jun 24 16:32:18 volumio sudo[2684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:32:18 volumio volumio[849]: info: Output device has changed, restarting Shairport Sync Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:18 volumio sudo[2684]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:32:18 volumio sudo[2684]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:18 volumio sudo[2686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:32:18 volumio sudo[2686]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:18 volumio systemd[1]: Stopping Music Player Daemon... Jun 24 16:32:18 volumio volumio[849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:32:18 volumio volumio[849]: info: Volume configurations have been set Jun 24 16:32:18 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:32:18 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:32:18 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 24 16:32:18 volumio volumio[849]: info: Updating Volume Controller Parameters: Device: 5 Name: Serenade DSD Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 24 16:32:18 volumio volumio[849]: info: Disabling external Volume Control Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 24 16:32:18 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:18 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:18 volumio sudo[2695]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 24 16:32:18 volumio sudo[2695]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:18 volumio sudo[2695]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:18 volumio volumio[849]: error: MPD error: Error: write EPIPE Jun 24 16:32:18 volumio volumio[849]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jun 24 16:32:18 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:18 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:18 volumio volumio[849]: info: MPD Permissions set Jun 24 16:32:18 volumio volumio[849]: Unable to parse: Jun 24 16:32:18 volumio volumio[849]: Simple mixer control 'PCM',0 Jun 24 16:32:18 volumio volumio[849]: Capabilities: pswitch pswitch-joined Jun 24 16:32:18 volumio volumio[849]: Playback channels: Mono Jun 24 16:32:18 volumio volumio[849]: Mono: Playback [on] Jun 24 16:32:18 volumio volumio[849]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jun 24 16:32:18 volumio volumio[849]: info: Software Volume ALSA configuration written Jun 24 16:32:18 volumio volumio[849]: info: Preparing to generate the ALSA configuration file Jun 24 16:32:18 volumio volumio[849]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 24 16:32:18 volumio volumio[849]: info: Reading ALSA contributions from plugins. Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 24 16:32:18 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 24 16:32:19 volumio volumio[849]: Unable to parse: Jun 24 16:32:19 volumio volumio[849]: Simple mixer control 'PCM',0 Jun 24 16:32:19 volumio volumio[849]: Capabilities: pswitch pswitch-joined Jun 24 16:32:19 volumio volumio[849]: Playback channels: Mono Jun 24 16:32:19 volumio volumio[849]: Mono: Playback [on] Jun 24 16:32:19 volumio volumio[849]: info: VolumeController:: Volume=undefined Mute =false Jun 24 16:32:19 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:19 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:19 volumio volumio[849]: info: Starting Shairport Sync Jun 24 16:32:19 volumio volumio[849]: info: Asound.conf file written Jun 24 16:32:19 volumio sudo[2718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 24 16:32:19 volumio sudo[2718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 24 16:32:19 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 24 16:32:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 24 16:32:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 24 16:32:19 volumio sudo[2721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 24 16:32:19 volumio sudo[2721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:19 volumio sudo[2718]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:19 volumio sudo[2721]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:19 volumio volumio[849]: No state is present for card DSD Jun 24 16:32:19 volumio volumio[849]: Found hardware: "USB-Audio" "USB Mixer" "USB22e1:ca01" "" "" Jun 24 16:32:19 volumio volumio[849]: Hardware is initialized using a generic method Jun 24 16:32:19 volumio volumio[849]: No state is present for card DSD Jun 24 16:32:19 volumio volumio[849]: info: Output device has changed, restarting MPD Jun 24 16:32:19 volumio sudo[2730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:32:19 volumio sudo[2730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:19 volumio volumio[849]: info: Output device has changed, restarting Shairport Sync Jun 24 16:32:19 volumio sudo[2730]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:19 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:32:19 volumio sudo[2732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:32:19 volumio sudo[2732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 24 16:32:19 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:32:19 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:32:19 volumio volumio[849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:32:19 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:32:19 volumio sudo[2738]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 24 16:32:19 volumio sudo[2738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:19 volumio sudo[2738]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:21 volumio volumio[849]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 24 16:32:21 volumio volumio[849]: No state is present for card DSD Jun 24 16:32:21 volumio volumio[849]: Found hardware: "USB-Audio" "USB Mixer" "USB22e1:ca01" "" "" Jun 24 16:32:21 volumio volumio[849]: Hardware is initialized using a generic method Jun 24 16:32:21 volumio volumio[849]: No state is present for card DSD Jun 24 16:32:21 volumio volumio[849]: info: Output device has changed, restarting MPD Jun 24 16:32:21 volumio sudo[2760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 16:32:21 volumio sudo[2760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:21 volumio volumio[849]: info: Output device has changed, restarting Shairport Sync Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:32:21 volumio sudo[2760]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:21 volumio sudo[2763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:32:21 volumio sudo[2763]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:21 volumio volumio[849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 16:32:21 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 24 16:32:21 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:32:21 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:32:21 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 24 16:32:21 volumio volumio[849]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 24 16:32:21 volumio volumio[849]: info: Disabling external Volume Control Jun 24 16:32:21 volumio sudo[2768]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 24 16:32:21 volumio sudo[2768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:21 volumio sudo[2768]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:21 volumio volumio[849]: info: VolumeController::SetAlsaVolume0 Jun 24 16:32:21 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:21 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:21 volumio volumio[849]: info: MPD Permissions set Jun 24 16:32:21 volumio volumio[849]: info: MPD Permissions set Jun 24 16:32:21 volumio volumio[849]: info: Shairport-Sync Started Jun 24 16:32:21 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:21 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 24 16:32:21 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 16:32:21 volumio volumio[849]: info: Starting Shairport Sync Jun 24 16:32:22 volumio volumio[849]: info: Starting Shairport Sync Jun 24 16:32:22 volumio sudo[2797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 24 16:32:22 volumio sudo[2797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:22 volumio sudo[2795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 24 16:32:22 volumio sudo[2795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 24 16:32:22 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 24 16:32:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 24 16:32:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 24 16:32:22 volumio sudo[2795]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:22 volumio sudo[2797]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:22 volumio volumio[849]: info: Shairport-Sync Started Jun 24 16:32:22 volumio volumio[849]: info: CoreCommandRouter::volumioPlay Jun 24 16:32:22 volumio volumio[849]: info: CoreStateMachine::play index undefined Jun 24 16:32:22 volumio volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:32:22 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:22 volumio volumio[849]: info: CoreStateMachine::startPlaybackTimer Jun 24 16:32:22 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:22 volumio volumio[849]: verbose: ControllerMpd::clearAddPlayTracks NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac Jun 24 16:32:22 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:32:22 volumio volumio[849]: info: VolumeController::SetAlsaVolume4 Jun 24 16:32:22 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:22 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:22 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:22 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 24 16:32:23 volumio mpd[2773]: Jun 24 16:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 16:32:23 volumio sudo[2805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 16:32:23 volumio sudo[2805]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:23 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 24 16:32:23 volumio systemd[1]: mpd.service: Succeeded. Jun 24 16:32:23 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 16:32:23 volumio systemd[1]: Starting Music Player Daemon... Jun 24 16:32:23 volumio sudo[2807]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 24 16:32:23 volumio sudo[2807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 16:32:23 volumio sudo[2807]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:24 volumio mpd[2809]: Jun 24 16:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 16:32:25 volumio systemd[1]: Started Music Player Daemon. Jun 24 16:32:25 volumio sudo[2805]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:25 volumio sudo[2732]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:25 volumio sudo[2686]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:25 volumio sudo[2763]: pam_unix(sudo:session): session closed for user root Jun 24 16:32:25 volumio volumio[849]: error: MPD error: The expression evaluated to a falsy value: Jun 24 16:32:25 volumio volumio[849]: assert.ok(self.idling) Jun 24 16:32:25 volumio volumio[849]: error: The expression evaluated to a falsy value: Jun 24 16:32:25 volumio volumio[849]: assert.ok(self.idling) Jun 24 16:32:25 volumio volumio[849]: error: MPD error: The expression evaluated to a falsy value: Jun 24 16:32:25 volumio volumio[849]: assert.ok(self.idling) Jun 24 16:32:25 volumio volumio[849]: error: The expression evaluated to a falsy value: Jun 24 16:32:25 volumio volumio[849]: assert.ok(self.idling) Jun 24 16:32:25 volumio volumio[849]: error: MPD error: The expression evaluated to a falsy value: Jun 24 16:32:25 volumio volumio[849]: assert.ok(self.idling) Jun 24 16:32:25 volumio volumio[849]: error: The expression evaluated to a falsy value: Jun 24 16:32:25 volumio volumio[849]: assert.ok(self.idling) Jun 24 16:32:25 volumio volumio[849]: error: updateQueue error: null Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPlay Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::play index undefined Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::startPlaybackTimer Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::clearAddPlayTracks NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand stop took 1 milliseconds Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand clear Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces system playlist update Jun 24 16:32:31 volumio volumio[849]: info: Ignoring MPD Status Update Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand clear took 1 milliseconds Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand add "NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac" Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces system playlist update Jun 24 16:32:31 volumio volumio[849]: info: Ignoring MPD Status Update Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces system playlist update Jun 24 16:32:31 volumio volumio[849]: info: Ignoring MPD Status Update Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces system playlist update Jun 24 16:32:31 volumio volumio[849]: info: Ignoring MPD Status Update Jun 24 16:32:31 volumio volumio[849]: error: updateQueue error: null Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces system playlist update Jun 24 16:32:31 volumio volumio[849]: info: Ignoring MPD Status Update Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 6ms Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand add "NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac" took 6 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 5ms Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 4ms Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 3ms Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand play Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces system playlist update Jun 24 16:32:31 volumio volumio[849]: info: Ignoring MPD Status Update Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces system playlist update Jun 24 16:32:31 volumio volumio[849]: info: Ignoring MPD Status Update Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces system playlist update Jun 24 16:32:31 volumio volumio[849]: info: Ignoring MPD Status Update Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 6ms Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand play took 5 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 5ms Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 3ms Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 3ms Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand status took 21 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand status took 23 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand status took 22 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand status took 22 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand status took 5 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:31 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus stop Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 40ms Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:31 volumio volumio[849]: info: Jun 24 16:32:31 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand status took 8 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand status took 6 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand status took 6 milliseconds Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:31 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:31 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:31 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:31 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:31 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:31 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"335 Kbps","isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:31 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:31 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 212ms Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 211ms Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 214ms Jun 24 16:32:31 volumio volumio[849]: info: ------------------------------ 202ms Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand playlistinfo took 168 milliseconds Jun 24 16:32:31 volumio volumio[849]: info: sendMpdCommand playlistinfo took 168 milliseconds Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:32:31 volumio volumio[849]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"335 Kbps","isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:31 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:31 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"335 Kbps","isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:31 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:31 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"335 Kbps","isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:31 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:31 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:31 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:31 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:31 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:32 volumio volumio[849]: info: ------------------------------ 353ms Jun 24 16:32:32 volumio volumio[849]: info: ------------------------------ 357ms Jun 24 16:32:32 volumio volumio[849]: info: ------------------------------ 358ms Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::volumioPrevious Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::previous Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: info: ControllerMpd::seek Jun 24 16:32:32 volumio volumio[849]: error: null Jun 24 16:32:32 volumio volumio[849]: info: Jun 24 16:32:32 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:32 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:32 volumio volumio[849]: info: Jun 24 16:32:32 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:32 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:32 volumio volumio[849]: info: Jun 24 16:32:32 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:32 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:32 volumio volumio[849]: info: Jun 24 16:32:32 volumio volumio[849]: ---------------------------- MPD announces state update: player Jun 24 16:32:32 volumio volumio[849]: info: ControllerMpd::getState Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jun 24 16:32:32 volumio volumio[849]: info: sendMpdCommand status took 9 milliseconds Jun 24 16:32:32 volumio volumio[849]: info: sendMpdCommand status took 7 milliseconds Jun 24 16:32:32 volumio volumio[849]: info: sendMpdCommand status took 5 milliseconds Jun 24 16:32:32 volumio volumio[849]: info: sendMpdCommand status took 3 milliseconds Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::parseState Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 16:32:32 volumio volumio[849]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 24 16:32:32 volumio volumio[849]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 24 16:32:32 volumio volumio[849]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 24 16:32:32 volumio volumio[849]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:32:32 volumio volumio[849]: verbose: ControllerMpd::parseTrackInfo Jun 24 16:32:32 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"466 Kbps","isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:32 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:32 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:32 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"350 Kbps","isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:32 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:32 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:32 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"337 Kbps","isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:32 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:32 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:32 volumio volumio[849]: info: ControllerMpd::pushState Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::servicePushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"335 Kbps","isStreaming":false,"title":"卫兰 - 听身体唱歌 .flac","artist":null,"album":null,"uri":"NAS/uuuu/首首精选/卫兰 - 听身体唱歌 .flac","trackType":"flac"} Jun 24 16:32:32 volumio volumio[849]: verbose: CURRENT POSITION 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::syncState stateService play Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::syncState currentStatus play Jun 24 16:32:32 volumio volumio[849]: info: Received an update from plugin. extracting info from payload Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:32 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:32 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:32 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:32 volumio volumio[849]: info: ------------------------------ 150ms Jun 24 16:32:32 volumio volumio[849]: info: ------------------------------ 147ms Jun 24 16:32:32 volumio volumio[849]: info: ------------------------------ 146ms Jun 24 16:32:32 volumio volumio[849]: info: ------------------------------ 145ms Jun 24 16:32:39 volumio volumio[849]: info: VolumeController::SetAlsaVolume20 Jun 24 16:32:39 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:39 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:39 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:39 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:40 volumio volumio[849]: info: VolumeController::SetAlsaVolume30 Jun 24 16:32:40 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:40 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:40 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:40 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:40 volumio volumio[849]: info: VolumeController::SetAlsaVolume45 Jun 24 16:32:40 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:40 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:40 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:40 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:43 volumio volumio[849]: info: VolumeController::SetAlsaVolume55 Jun 24 16:32:43 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:43 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:43 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:43 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:44 volumio volumio[849]: info: VolumeController::SetAlsaVolume65 Jun 24 16:32:44 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:44 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:44 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:44 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:46 volumio volumio[849]: info: VolumeController::SetAlsaVolume70 Jun 24 16:32:46 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:46 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:46 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:46 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:47 volumio volumio[849]: info: VolumeController::SetAlsaVolume65 Jun 24 16:32:47 volumio volumio[849]: info: CoreStateMachine::pushState Jun 24 16:32:47 volumio volumio[849]: info: CorePlayQueue::getTrack 2 Jun 24 16:32:47 volumio volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 16:32:47 volumio volumio[849]: info: CoreCommandRouter::volumioPushState Jun 24 16:32:56 volumio volumio[849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:32:56 volumio volumio[849]: Error: connect ETIMEDOUT 157.240.15.8:443 Jun 24 16:32:56 volumio volumio[849]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 24 16:32:56 volumio volumio[849]: errno: -110, Jun 24 16:32:56 volumio volumio[849]: code: 'ETIMEDOUT', Jun 24 16:32:56 volumio volumio[849]: syscall: 'connect', Jun 24 16:32:56 volumio volumio[849]: address: '157.240.15.8', Jun 24 16:32:56 volumio volumio[849]: port: 443 Jun 24 16:32:56 volumio volumio[849]: } Jun 24 16:32:56 volumio volumio[849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 16:32:57 volumio sudo[2883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 16:31 Jun 24 16:32:57 volumio sudo[2883]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"