-- Logs begin at Thu 2024-10-24 09:27:24 CST, end at Thu 2024-10-24 10:15:22 CST. -- Oct 24 10:14:09 volumio-603 volumio[1085]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioGetState Oct 24 10:14:09 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Oct 24 10:14:09 volumio-603 volumio[1085]: info: Restoring Previous Volume level: 100 false true Oct 24 10:14:09 volumio-603 volumio[1085]: info: VolumeController::SetAlsaVolume100 Oct 24 10:14:09 volumio-603 volumio[1085]: info: Enable softmixer device for audio device number 5 Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioStop Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreStateMachine::stop Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreStateMachine::stPlaybackTimer Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreStateMachine::updateTrackBlock Oct 24 10:14:09 volumio-603 volumio[1085]: info: CorePlayQueue::getTrackBlock Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:09 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreStateMachine::serviceStop Oct 24 10:14:09 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:09 volumio-603 volumio[1085]: info: CoreCommandRouter::serviceStop Oct 24 10:14:09 volumio-603 volumio[1085]: info: ControllerMpd::stop Oct 24 10:14:09 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 10:14:09 volumio-603 volumio[1085]: info: Enable softmixer device for audio device undefined Oct 24 10:14:10 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 24 10:14:10 volumio-603 volumio[1085]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 24 10:14:10 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 24 10:14:10 volumio-603 volumio[1085]: info: Disabling external Volume Control Oct 24 10:14:10 volumio-603 sudo[7633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 10:14:10 volumio-603 sudo[7633]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:10 volumio-603 sudo[7634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 10:14:10 volumio-603 sudo[7634]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:10 volumio-603 volumio[1085]: info: Output device has changed, restarting MPD Oct 24 10:14:10 volumio-603 sudo[7634]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:10 volumio-603 systemd[1]: Stopping Music Player Daemon... Oct 24 10:14:10 volumio-603 volumio[1085]: info: Output device has changed, restarting Shairport Sync Oct 24 10:14:10 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:10 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:10 volumio-603 sudo[7645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 10:14:10 volumio-603 sudo[7645]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:10 volumio-603 sudo[7643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 10:14:10 volumio-603 sudo[7643]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:10 volumio-603 sudo[7643]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:10 volumio-603 volumio[1085]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 10:14:10 volumio-603 volumio[1085]: info: Volume configurations have been set Oct 24 10:14:10 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 24 10:14:10 volumio-603 volumio[1085]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 24 10:14:10 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 24 10:14:10 volumio-603 volumio[1085]: info: Disabling external Volume Control Oct 24 10:14:10 volumio-603 volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 10:14:10 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:10 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:10 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:10 volumio-603 volumio[1085]: error: MPD error: Error: write EPIPE Oct 24 10:14:10 volumio-603 volumio[1085]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Oct 24 10:14:10 volumio-603 volumio[1085]: info: MPD Permissions set Oct 24 10:14:10 volumio-603 volumio[1085]: info: MPD Permissions set Oct 24 10:14:10 volumio-603 volumio[1085]: info: Software Volume ALSA configuration written Oct 24 10:14:10 volumio-603 volumio[1085]: info: Preparing to generate the ALSA configuration file Oct 24 10:14:12 volumio-603 volumio[1085]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 24 10:14:12 volumio-603 volumio[1085]: info: Retrying Login Due to Network Error Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 24 10:14:12 volumio-603 volumio[1085]: info: Reading ALSA contributions from plugins. Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:12 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:12 volumio-603 volumio[1085]: info: VolumeController:: Volume=100 Mute =false Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:12 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 24 10:14:12 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 24 10:14:12 volumio-603 systemd[1]: mpd.service: Succeeded. Oct 24 10:14:12 volumio-603 systemd[1]: Stopped Music Player Daemon. Oct 24 10:14:12 volumio-603 volumio[1085]: info: Starting Shairport Sync Oct 24 10:14:12 volumio-603 systemd[1]: Starting Music Player Daemon... Oct 24 10:14:12 volumio-603 sudo[7680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 10:14:12 volumio-603 sudo[7680]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:12 volumio-603 volumio[1085]: info: Asound.conf file written Oct 24 10:14:12 volumio-603 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 10:14:12 volumio-603 systemd[1]: shairport-sync.service: Succeeded. Oct 24 10:14:12 volumio-603 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 10:14:12 volumio-603 sudo[7683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 24 10:14:12 volumio-603 sudo[7683]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:12 volumio-603 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 10:14:13 volumio-603 sudo[7680]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:13 volumio-603 sudo[7683]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:13 volumio-603 sudo[7677]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 10:14:13 volumio-603 sudo[7677]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:13 volumio-603 sudo[7677]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:13 volumio-603 volumio[1085]: info: Output device has changed, restarting MPD Oct 24 10:14:13 volumio-603 volumio[1085]: info: Output device has changed, restarting Shairport Sync Oct 24 10:14:13 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:13 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:13 volumio-603 sudo[7693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 10:14:13 volumio-603 sudo[7693]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:13 volumio-603 sudo[7693]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:13 volumio-603 sudo[7695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 10:14:13 volumio-603 sudo[7695]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:13 volumio-603 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 10:14:13 volumio-603 systemd[1]: mpd.service: Succeeded. Oct 24 10:14:13 volumio-603 systemd[1]: Stopped Music Player Daemon. Oct 24 10:14:13 volumio-603 volumio[1085]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 10:14:13 volumio-603 systemd[1]: Starting Music Player Daemon... Oct 24 10:14:13 volumio-603 sudo[7702]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 10:14:13 volumio-603 sudo[7702]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:13 volumio-603 sudo[7702]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:14 volumio-603 volumio[1085]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 24 10:14:14 volumio-603 volumio[1085]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:071a" "" "" Oct 24 10:14:14 volumio-603 volumio[1085]: Hardware is initialized using a generic method Oct 24 10:14:14 volumio-603 volumio[1085]: info: Output device has changed, restarting MPD Oct 24 10:14:14 volumio-603 volumio[1085]: info: Output device has changed, restarting Shairport Sync Oct 24 10:14:14 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:14 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:14 volumio-603 sudo[7711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 10:14:14 volumio-603 sudo[7711]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:14 volumio-603 sudo[7711]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:14 volumio-603 sudo[7713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 10:14:14 volumio-603 sudo[7713]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:14 volumio-603 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 10:14:14 volumio-603 systemd[1]: mpd.service: Succeeded. Oct 24 10:14:14 volumio-603 systemd[1]: Stopped Music Player Daemon. Oct 24 10:14:14 volumio-603 volumio[1085]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 10:14:14 volumio-603 systemd[1]: Starting Music Player Daemon... Oct 24 10:14:14 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 24 10:14:14 volumio-603 volumio[1085]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 24 10:14:15 volumio-603 volumio[1085]: info: Disabling external Volume Control Oct 24 10:14:15 volumio-603 volumio[1085]: info: VolumeController::SetAlsaVolume100 Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:15 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:15 volumio-603 sudo[7719]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 10:14:15 volumio-603 sudo[7719]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:15 volumio-603 sudo[7719]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:15 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:15 volumio-603 volumio[1085]: info: MPD Permissions set Oct 24 10:14:15 volumio-603 volumio[1085]: info: Shairport-Sync Started Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 24 10:14:15 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:15 volumio-603 volumio[1085]: info: Starting Shairport Sync Oct 24 10:14:15 volumio-603 volumio[1085]: info: Starting Shairport Sync Oct 24 10:14:15 volumio-603 sudo[7746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 10:14:15 volumio-603 sudo[7746]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:15 volumio-603 sudo[7748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 10:14:15 volumio-603 sudo[7748]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:15 volumio-603 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 10:14:15 volumio-603 systemd[1]: shairport-sync.service: Succeeded. Oct 24 10:14:15 volumio-603 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 10:14:15 volumio-603 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 10:14:15 volumio-603 sudo[7746]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:15 volumio-603 sudo[7748]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:15 volumio-603 volumio[1085]: info: Shairport-Sync Started Oct 24 10:14:15 volumio-603 volumio[1085]: info: Shairport-Sync Started Oct 24 10:14:16 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 24 10:14:16 volumio-603 sudo[7756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 10:14:16 volumio-603 sudo[7756]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:16 volumio-603 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 10:14:16 volumio-603 systemd[1]: mpd.service: Succeeded. Oct 24 10:14:16 volumio-603 systemd[1]: Stopped Music Player Daemon. Oct 24 10:14:16 volumio-603 systemd[1]: Starting Music Player Daemon... Oct 24 10:14:16 volumio-603 sudo[7758]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 10:14:16 volumio-603 sudo[7758]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:16 volumio-603 sudo[7758]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:19 volumio-603 mpd[7760]: Oct 24 10:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 10:14:19 volumio-603 systemd[1]: Started Music Player Daemon. Oct 24 10:14:19 volumio-603 volumio[1085]: Upnp client error: Error: This socket has been ended by the other party Oct 24 10:14:19 volumio-603 volumio[1085]: Upnp client error: Error: This socket has been ended by the other party Oct 24 10:14:19 volumio-603 sudo[7645]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:19 volumio-603 sudo[7713]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:19 volumio-603 sudo[7695]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:19 volumio-603 sudo[7633]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:19 volumio-603 sudo[7756]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:19 volumio-603 volumio[1085]: error: MPD error: The expression evaluated to a falsy value: Oct 24 10:14:19 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:19 volumio-603 volumio[1085]: error: The expression evaluated to a falsy value: Oct 24 10:14:19 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:19 volumio-603 volumio[1085]: error: MPD error: The expression evaluated to a falsy value: Oct 24 10:14:19 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:19 volumio-603 volumio[1085]: error: The expression evaluated to a falsy value: Oct 24 10:14:19 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:19 volumio-603 volumio[1085]: error: MPD error: The expression evaluated to a falsy value: Oct 24 10:14:19 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:19 volumio-603 volumio[1085]: error: The expression evaluated to a falsy value: Oct 24 10:14:19 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:19 volumio-603 volumio[1085]: error: MPD error: The expression evaluated to a falsy value: Oct 24 10:14:19 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:19 volumio-603 volumio[1085]: error: The expression evaluated to a falsy value: Oct 24 10:14:19 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:19 volumio-603 volumio[1085]: error: updateQueue error: null Oct 24 10:14:22 volumio-603 volumio[1085]: info: MyVolumio login type: Token Oct 24 10:14:29 volumio-603 volumio[1085]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 24 10:14:29 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 24 10:14:29 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioGetState Oct 24 10:14:29 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:29 volumio-603 volumio[1085]: info: Disable softmixer device for audio device Oct 24 10:14:29 volumio-603 volumio[1085]: info: Output device has changed, restarting MPD Oct 24 10:14:29 volumio-603 volumio[1085]: info: Output device has changed, restarting Shairport Sync Oct 24 10:14:29 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:29 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:29 volumio-603 sudo[7786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 10:14:29 volumio-603 sudo[7786]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:29 volumio-603 sudo[7786]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:29 volumio-603 sudo[7788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 10:14:29 volumio-603 sudo[7788]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:29 volumio-603 systemd[1]: Stopping Music Player Daemon... Oct 24 10:14:29 volumio-603 volumio[1085]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 10:14:29 volumio-603 volumio[1085]: info: Restoring Previous Volume level: 100 false false Oct 24 10:14:29 volumio-603 volumio[1085]: info: Volume configurations have been set Oct 24 10:14:29 volumio-603 systemd[1]: mpd.service: Succeeded. Oct 24 10:14:29 volumio-603 systemd[1]: Stopped Music Player Daemon. Oct 24 10:14:30 volumio-603 systemd[1]: Starting Music Player Daemon... Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 24 10:14:30 volumio-603 volumio[1085]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 24 10:14:30 volumio-603 volumio[1085]: info: Disabling external Volume Control Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 10:14:30 volumio-603 volumio[1085]: info: Soft Volume ALSA configuration file deleted Oct 24 10:14:30 volumio-603 volumio[1085]: info: Preparing to generate the ALSA configuration file Oct 24 10:14:30 volumio-603 volumio[1085]: info: MPD Permissions set Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:30 volumio-603 sudo[7795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 10:14:30 volumio-603 sudo[7795]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:30 volumio-603 sudo[7795]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 24 10:14:30 volumio-603 volumio[1085]: info: VolumeController:: Volume=100 Mute =false Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:30 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:14:30 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:30 volumio-603 volumio[1085]: info: Starting Shairport Sync Oct 24 10:14:30 volumio-603 volumio[1085]: info: Asound.conf file written Oct 24 10:14:30 volumio-603 sudo[7812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 10:14:30 volumio-603 sudo[7812]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:30 volumio-603 sudo[7814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 24 10:14:30 volumio-603 sudo[7814]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:30 volumio-603 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 10:14:30 volumio-603 sudo[7814]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:30 volumio-603 systemd[1]: shairport-sync.service: Succeeded. Oct 24 10:14:30 volumio-603 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 10:14:30 volumio-603 volumio[1085]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:071a" "" "" Oct 24 10:14:30 volumio-603 volumio[1085]: Hardware is initialized using a generic method Oct 24 10:14:30 volumio-603 volumio[1085]: info: Output device has changed, restarting MPD Oct 24 10:14:30 volumio-603 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 10:14:31 volumio-603 sudo[7812]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:31 volumio-603 volumio[1085]: info: Output device has changed, restarting Shairport Sync Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:31 volumio-603 sudo[7821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 10:14:31 volumio-603 sudo[7821]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:31 volumio-603 sudo[7823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 10:14:31 volumio-603 sudo[7821]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:31 volumio-603 sudo[7823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:31 volumio-603 volumio[1085]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 10:14:31 volumio-603 volumio[1085]: info: MPD Permissions set Oct 24 10:14:31 volumio-603 volumio[1085]: info: Shairport-Sync Started Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 systemd[1]: mpd.service: Succeeded. Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 systemd[1]: Stopped Music Player Daemon. Oct 24 10:14:31 volumio-603 systemd[1]: Starting Music Player Daemon... Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 24 10:14:31 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 10:14:31 volumio-603 volumio[1085]: info: Starting Shairport Sync Oct 24 10:14:31 volumio-603 sudo[7839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 10:14:31 volumio-603 sudo[7839]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:31 volumio-603 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 10:14:31 volumio-603 systemd[1]: shairport-sync.service: Succeeded. Oct 24 10:14:31 volumio-603 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 10:14:31 volumio-603 sudo[7831]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 10:14:31 volumio-603 sudo[7831]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:14:31 volumio-603 sudo[7831]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:31 volumio-603 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 10:14:31 volumio-603 sudo[7839]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:31 volumio-603 volumio[1085]: info: Shairport-Sync Started Oct 24 10:14:33 volumio-603 mpd[7843]: Oct 24 10:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 10:14:33 volumio-603 systemd[1]: Started Music Player Daemon. Oct 24 10:14:33 volumio-603 sudo[7788]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:33 volumio-603 sudo[7823]: pam_unix(sudo:session): session closed for user root Oct 24 10:14:33 volumio-603 volumio[1085]: error: MPD error: The expression evaluated to a falsy value: Oct 24 10:14:33 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:33 volumio-603 volumio[1085]: error: The expression evaluated to a falsy value: Oct 24 10:14:33 volumio-603 volumio[1085]: assert.ok(self.idling) Oct 24 10:14:33 volumio-603 volumio[1085]: error: updateQueue error: null Oct 24 10:14:34 volumio-603 volumio[1085]: info: VolumeController::SetAlsaVolume100 Oct 24 10:14:34 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:34 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:34 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:38 volumio-603 volumiologrotate[699]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 24 10:14:38 volumio-603 volumiologrotate[699]: ls: cannot access '603': No such file or directory Oct 24 10:14:52 volumio-603 volumio[1085]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 24 10:14:52 volumio-603 volumio[1085]: info: Retrying Login Due to Network Error Oct 24 10:14:56 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioGetState Oct 24 10:14:56 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPlay Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::play index undefined Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::startPlaybackTimer Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand stop took 4 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 10:14:57 volumio-603 volumio[1085]: info: Oct 24 10:14:57 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:14:57 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand clear took 6 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav" Oct 24 10:14:57 volumio-603 volumio[1085]: info: Oct 24 10:14:57 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:14:57 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:14:57 volumio-603 volumio[1085]: error: updateQueue error: null Oct 24 10:14:57 volumio-603 volumio[1085]: info: Oct 24 10:14:57 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:14:57 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:14:57 volumio-603 volumio[1085]: info: ------------------------------ 19ms Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand add "INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav" took 14 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: info: ------------------------------ 13ms Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand play Oct 24 10:14:57 volumio-603 volumio[1085]: info: Oct 24 10:14:57 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:14:57 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:14:57 volumio-603 volumio[1085]: info: Oct 24 10:14:57 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:14:57 volumio-603 volumio[1085]: info: ------------------------------ 17ms Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand play took 9 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:14:57 volumio-603 volumio[1085]: info: ------------------------------ 8ms Oct 24 10:14:57 volumio-603 volumio[1085]: info: Oct 24 10:14:57 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:14:57 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:14:57 volumio-603 volumio[1085]: info: Oct 24 10:14:57 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand status took 54 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand status took 53 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:14:57 volumio-603 volumio[1085]: info: Oct 24 10:14:57 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:14:57 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand status took 9 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:14:57 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Oct 24 10:14:57 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:14:57 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: ------------------------------ 102ms Oct 24 10:14:57 volumio-603 volumio[1085]: info: ------------------------------ 119ms Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand status took 61 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 56 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:14:57 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:14:57 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: ------------------------------ 131ms Oct 24 10:14:57 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 69 milliseconds Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:14:57 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:14:57 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:14:57 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:14:57 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:14:57 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:14:58 volumio-603 volumio[1085]: info: ------------------------------ 236ms Oct 24 10:15:02 volumio-603 volumio[1085]: info: MyVolumio login type: Token Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 10:15:04 volumio-603 volumio[1085]: info: Retrieving Cloud Streaming UI Oct 24 10:15:04 volumio-603 volumio[1085]: info: Getting Tidal Cloud Configuration Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 10:15:04 volumio-603 volumio[1085]: info: Getting Qobuz Cloud Configuration Oct 24 10:15:04 volumio-603 volumio[1085]: info: Asking plugin for UI Config Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 10:15:04 volumio-603 volumio[1085]: info: Getting Spotify Cloud Configuration Oct 24 10:15:04 volumio-603 volumio[1085]: info: Asking plugin for UI Config Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 10:15:04 volumio-603 volumio[1085]: info: Saving Spotify Acccount Oct 24 10:15:04 volumio-603 volumio[1085]: info: Got Tidal Cloud Configuration Oct 24 10:15:04 volumio-603 volumio[1085]: info: Got it Oct 24 10:15:04 volumio-603 volumio[1085]: info: Got it Oct 24 10:15:04 volumio-603 volumio[1085]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 10:15:04 volumio-603 volumio[1085]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 10:15:04 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 24 10:15:05 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 24 10:15:05 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand update Oct 24 10:15:05 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:05 volumio-603 volumio[1085]: info: Oct 24 10:15:05 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: update Oct 24 10:15:05 volumio-603 volumio[1085]: info: sendMpdCommand update took 6 milliseconds Oct 24 10:15:05 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:05 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:05 volumio-603 volumio[1085]: info: sendMpdCommand status took 5 milliseconds Oct 24 10:15:05 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:06 volumio-603 volumio[1085]: info: Command Router : Notfying DB Updatetrue Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::Close All Modals sent Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:06 volumio-603 volumio[1085]: info: Oct 24 10:15:06 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: update Oct 24 10:15:06 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:06 volumio-603 volumio[1085]: info: sendMpdCommand status took 273 milliseconds Oct 24 10:15:06 volumio-603 volumio[1085]: info: sendMpdCommand status took 5 milliseconds Oct 24 10:15:06 volumio-603 volumio[1085]: info: sendMpdCommand status took 3 milliseconds Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:06 volumio-603 volumio[1085]: info: Command Router : Notfying DB Updatetrue Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::Close All Modals sent Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:06 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 440 milliseconds Oct 24 10:15:06 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:06 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8491,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:15:06 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8742,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:15:06 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:15:06 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:06 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:06 volumio-603 volumio[1085]: info: ------------------------------ 822ms Oct 24 10:15:06 volumio-603 volumio[1085]: info: ------------------------------ 567ms Oct 24 10:15:08 volumio-603 volumio[1085]: info: MPD Database updated - AlbumList cache refreshed Oct 24 10:15:08 volumio-603 volumio[1085]: info: Oct 24 10:15:08 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: database Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:08 volumio-603 volumio[1085]: info: Oct 24 10:15:08 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: update Oct 24 10:15:08 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:08 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:08 volumio-603 volumio[1085]: info: MPD Database updated - AlbumList cache refreshed Oct 24 10:15:08 volumio-603 volumio[1085]: info: Oct 24 10:15:08 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: database Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:08 volumio-603 volumio[1085]: info: Oct 24 10:15:08 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: update Oct 24 10:15:08 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:08 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand status took 44 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand status took 43 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand status took 49 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:08 volumio-603 volumio[1085]: info: Command Router : Notfying DB Updatefalse Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::Close All Modals sent Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand status took 371 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand status took 370 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand status took 376 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:08 volumio-603 volumio[1085]: info: Command Router : Notfying DB Updatefalse Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::Close All Modals sent Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:08 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10745,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:15:08 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10745,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:15:08 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: ------------------------------ 595ms Oct 24 10:15:08 volumio-603 volumio[1085]: info: ------------------------------ 596ms Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 111 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 110 milliseconds Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:08 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10745,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:15:08 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10745,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"009.中国轻音乐 - 偏偏喜欢你.wav","artist":null,"album":null,"uri":"INTERNAL/中国轻音乐--古早茶/群星.-.[中国轻音乐.-.古早茶1].专辑/群星.-.[中国轻音乐.-.古早茶1].专辑/009.中国轻音乐 - 偏偏喜欢你.wav","trackType":"wav"} Oct 24 10:15:08 volumio-603 volumio[1085]: verbose: CURRENT POSITION 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:15:08 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:08 volumio-603 volumio[1085]: info: ------------------------------ 689ms Oct 24 10:15:08 volumio-603 volumio[1085]: info: ------------------------------ 691ms Oct 24 10:15:08 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioGetState Oct 24 10:15:08 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:10 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioGetQueue Oct 24 10:15:10 volumio-603 volumio[1085]: info: CoreStateMachine::getQueue Oct 24 10:15:10 volumio-603 volumio[1085]: info: CorePlayQueue::getQueue Oct 24 10:15:16 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 24 10:15:16 volumio-603 volumio[1085]: info: CURURI: music-library/NAS Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preload queue cleared Oct 24 10:15:16 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 24 10:15:16 volumio-603 volumio[1085]: info: CURURI: music-library/NAS/DSD Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preload queue cleared Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/01 -dsd64- 小城故事.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/02 - dsd64- 好花不常开.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/03 - dsd64- 难忘初恋的情人.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/05 - dsd64- 偿还.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/06 - dsd64- 丝丝小雨.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/07 - dsd64- 几多愁.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/08 -dsd64- 初次尝到寂寞.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/09 - dsd64- 北国之春.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/10 - dsd64- 又见炊烟.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/11 - dsd64- 一个小心愿.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/12 - dsd64- 一封情书.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/13 - dsd64- 假如我是真的.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/14 - dsd64- 春风满小城.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/15 - dsd64- 原乡情浓.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/16 - dsd64- 我心深处.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/17 - dsd64- 谁来爱我.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/01 - 蔡幸娟 - 转一圈.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/02 - 蔡幸娟 - 爱的花园.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/03 - 蔡幸娟 - 夜花娇.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/04 - 蔡幸娟 - 心肝宝贝.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/05 - dsd64 - 摇婴仔歌 (闽南语摇篮曲).dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/07 - dsd64 - 你是我的宝贝.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/09 - 蔡幸娟 - 祈祷.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/10 - dsd64 - 妈妈教我的歌.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/11 - 蔡幸娟 - 爱的花园 (伴奏).dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/12 - 蔡幸娟 - 夜花娇 (伴奏).dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/董小姐.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/蔡幸娟 - 高山青.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/蔡幸娟 - 只要为你活一天.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/蔡幸娟 - 春花梦露.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/dsd64- 等着你回来.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/永远的微笑.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/dsd64- 好预兆.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/dsd64- 天涯歌女.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/dsd64- 怀念.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/dsd64- 南屏晚钟.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/dsd64- 如果没有你.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD/dsd64- 午夜香吻.dsf Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/01 -dsd64- 小城故事.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/02 - dsd64- 好花不常开.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F01%20-dsd64-%20%E5%B0%8F%E5%9F%8E%E6%95%85%E4%BA%8B.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/01 -dsd64- 小城故事.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F02%20-%20dsd64-%20%E5%A5%BD%E8%8A%B1%E4%B8%8D%E5%B8%B8%E5%BC%80.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/02 - dsd64- 好花不常开.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/03 - dsd64- 难忘初恋的情人.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F03%20-%20dsd64-%20%E9%9A%BE%E5%BF%98%E5%88%9D%E6%81%8B%E7%9A%84%E6%83%85%E4%BA%BA.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/03 - dsd64- 难忘初恋的情人.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/05 - dsd64- 偿还.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F05%20-%20dsd64-%20%E5%81%BF%E8%BF%98.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/05 - dsd64- 偿还.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/06 - dsd64- 丝丝小雨.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F06%20-%20dsd64-%20%E4%B8%9D%E4%B8%9D%E5%B0%8F%E9%9B%A8.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/06 - dsd64- 丝丝小雨.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/07 - dsd64- 几多愁.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F07%20-%20dsd64-%20%E5%87%A0%E5%A4%9A%E6%84%81.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/07 - dsd64- 几多愁.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/08 -dsd64- 初次尝到寂寞.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F08%20-dsd64-%20%20%E5%88%9D%E6%AC%A1%E5%B0%9D%E5%88%B0%E5%AF%82%E5%AF%9E.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/08 -dsd64- 初次尝到寂寞.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/09 - dsd64- 北国之春.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F09%20-%20dsd64-%20%E5%8C%97%E5%9B%BD%E4%B9%8B%E6%98%A5.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/09 - dsd64- 北国之春.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/10 - dsd64- 又见炊烟.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F10%20-%20dsd64-%20%E5%8F%88%E8%A7%81%E7%82%8A%E7%83%9F.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/10 - dsd64- 又见炊烟.dff Oct 24 10:15:16 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/11 - dsd64- 一个小心愿.dff in service mpd Oct 24 10:15:16 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F11%20-%20dsd64-%20%E4%B8%80%E4%B8%AA%E5%B0%8F%E5%BF%83%E6%84%BF.dff&metadata=false Oct 24 10:15:16 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/11 - dsd64- 一个小心愿.dff Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/12 - dsd64- 一封情书.dff in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F12%20-%20dsd64-%20%E4%B8%80%E5%B0%81%E6%83%85%E4%B9%A6.dff&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/12 - dsd64- 一封情书.dff Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/13 - dsd64- 假如我是真的.dff in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F13%20-%20dsd64-%20%E5%81%87%E5%A6%82%E6%88%91%E6%98%AF%E7%9C%9F%E7%9A%84.dff&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/13 - dsd64- 假如我是真的.dff Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/14 - dsd64- 春风满小城.dff in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F14%20-%20dsd64-%20%E6%98%A5%E9%A3%8E%E6%BB%A1%E5%B0%8F%E5%9F%8E.dff&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/14 - dsd64- 春风满小城.dff Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/15 - dsd64- 原乡情浓.dff in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F15%20-%20dsd64-%20%E5%8E%9F%E4%B9%A1%E6%83%85%E6%B5%93.dff&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/15 - dsd64- 原乡情浓.dff Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/16 - dsd64- 我心深处.dff in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F16%20-%20dsd64-%20%E6%88%91%E5%BF%83%E6%B7%B1%E5%A4%84.dff&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/16 - dsd64- 我心深处.dff Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/17 - dsd64- 谁来爱我.dff in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=//extralarge&path=%2Fmnt%2FNAS%2FDSD%2F17%20-%20dsd64-%20%E8%B0%81%E6%9D%A5%E7%88%B1%E6%88%91.dff&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/17 - dsd64- 谁来爱我.dff Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/01 - 蔡幸娟 - 转一圈.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F01%20-%20%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E8%BD%AC%E4%B8%80%E5%9C%88.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/01 - 蔡幸娟 - 转一圈.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/02 - 蔡幸娟 - 爱的花园.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F02%20-%20%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E7%88%B1%E7%9A%84%E8%8A%B1%E5%9B%AD.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/02 - 蔡幸娟 - 爱的花园.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/03 - 蔡幸娟 - 夜花娇.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F03%20-%20%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E5%A4%9C%E8%8A%B1%E5%A8%87.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/03 - 蔡幸娟 - 夜花娇.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/04 - 蔡幸娟 - 心肝宝贝.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F04%20-%20%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E5%BF%83%E8%82%9D%E5%AE%9D%E8%B4%9D.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/04 - 蔡幸娟 - 心肝宝贝.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/05 - dsd64 - 摇婴仔歌 (闽南语摇篮曲).dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F05%20-%20dsd64%20-%20%E6%91%87%E5%A9%B4%E4%BB%94%E6%AD%8C%20(%E9%97%BD%E5%8D%97%E8%AF%AD%E6%91%87%E7%AF%AE%E6%9B%B2).dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/05 - dsd64 - 摇婴仔歌 (闽南语摇篮曲).dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/07 - dsd64 - 你是我的宝贝.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F07%20-%20dsd64%20-%20%E4%BD%A0%E6%98%AF%E6%88%91%E7%9A%84%E5%AE%9D%E8%B4%9D.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/07 - dsd64 - 你是我的宝贝.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/09 - 蔡幸娟 - 祈祷.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F09%20-%20%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E7%A5%88%E7%A5%B7.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/09 - 蔡幸娟 - 祈祷.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/10 - dsd64 - 妈妈教我的歌.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F10%20-%20dsd64%20-%20%E5%A6%88%E5%A6%88%E6%95%99%E6%88%91%E7%9A%84%E6%AD%8C.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/10 - dsd64 - 妈妈教我的歌.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/11 - 蔡幸娟 - 爱的花园 (伴奏).dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F11%20-%20%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E7%88%B1%E7%9A%84%E8%8A%B1%E5%9B%AD%20(%E4%BC%B4%E5%A5%8F).dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/11 - 蔡幸娟 - 爱的花园 (伴奏).dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/12 - 蔡幸娟 - 夜花娇 (伴奏).dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E5%A6%88%E5%A6%88%E6%83%85%E6%AD%8C%20%E8%BD%AC%E4%B8%80%E5%9C%88/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F12%20-%20%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E5%A4%9C%E8%8A%B1%E5%A8%87%20(%E4%BC%B4%E5%A5%8F).dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/12 - 蔡幸娟 - 夜花娇 (伴奏).dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/董小姐.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E5%AE%8B%E5%86%AC%E9%87%8E/%E5%AE%89%E5%92%8C%E6%A1%A5%E5%8C%97%20-Vinyl-/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F%E8%91%A3%E5%B0%8F%E5%A7%90.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/董小姐.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/蔡幸娟 - 高山青.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E7%9C%9F%E6%83%85/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E9%AB%98%E5%B1%B1%E9%9D%92.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/蔡幸娟 - 高山青.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/蔡幸娟 - 只要为你活一天.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E7%9C%9F%E6%83%85/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E5%8F%AA%E8%A6%81%E4%B8%BA%E4%BD%A0%E6%B4%BB%E4%B8%80%E5%A4%A9.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/蔡幸娟 - 只要为你活一天.dsf Oct 24 10:15:17 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/蔡幸娟 - 春花梦露.dsf in service mpd Oct 24 10:15:17 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E5%B9%B8%E5%A8%9F/%E7%9C%9F%E6%83%85/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F%E8%94%A1%E5%B9%B8%E5%A8%9F%20-%20%E6%98%A5%E8%8A%B1%E6%A2%A6%E9%9C%B2.dsf&metadata=false Oct 24 10:15:17 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/蔡幸娟 - 春花梦露.dsf Oct 24 10:15:18 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/dsd64- 等着你回来.dsf in service mpd Oct 24 10:15:18 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E7%90%B4/%E9%87%91%E7%89%87%E5%AD%90%20%E5%A3%B9%20%E5%A4%A9%E6%B6%AF%E6%AD%8C%E5%A5%B3/extralarge&path=%2Fmnt%2FNAS%2FDSD%2Fdsd64-%20%E7%AD%89%E7%9D%80%E4%BD%A0%E5%9B%9E%E6%9D%A5.dsf&metadata=false Oct 24 10:15:18 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/dsd64- 等着你回来.dsf Oct 24 10:15:18 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/永远的微笑.dsf in service mpd Oct 24 10:15:18 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E7%90%B4/%E9%87%91%E7%89%87%E5%AD%90%20%E5%A3%B9%20%E5%A4%A9%E6%B6%AF%E6%AD%8C%E5%A5%B3/extralarge&path=%2Fmnt%2FNAS%2FDSD%2F%E6%B0%B8%E8%BF%9C%E7%9A%84%E5%BE%AE%E7%AC%91.dsf&metadata=false Oct 24 10:15:18 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/永远的微笑.dsf Oct 24 10:15:18 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/dsd64- 好预兆.dsf in service mpd Oct 24 10:15:18 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E7%90%B4/%E9%87%91%E7%89%87%E5%AD%90%20%E5%A3%B9%20%E5%A4%A9%E6%B6%AF%E6%AD%8C%E5%A5%B3/extralarge&path=%2Fmnt%2FNAS%2FDSD%2Fdsd64-%20%E5%A5%BD%E9%A2%84%E5%85%86.dsf&metadata=false Oct 24 10:15:18 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/dsd64- 好预兆.dsf Oct 24 10:15:18 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/dsd64- 天涯歌女.dsf in service mpd Oct 24 10:15:18 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E7%90%B4/%E9%87%91%E7%89%87%E5%AD%90%20%E5%A3%B9%20%E5%A4%A9%E6%B6%AF%E6%AD%8C%E5%A5%B3/extralarge&path=%2Fmnt%2FNAS%2FDSD%2Fdsd64-%20%E5%A4%A9%E6%B6%AF%E6%AD%8C%E5%A5%B3.dsf&metadata=false Oct 24 10:15:18 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/dsd64- 天涯歌女.dsf Oct 24 10:15:18 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/dsd64- 怀念.dsf in service mpd Oct 24 10:15:18 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E7%90%B4/%E9%87%91%E7%89%87%E5%AD%90%20%E8%B4%B0%20%20%E9%AD%82%E8%90%A6%E6%97%A7%E6%A2%A6/extralarge&path=%2Fmnt%2FNAS%2FDSD%2Fdsd64-%20%E6%80%80%E5%BF%B5.dsf&metadata=false Oct 24 10:15:18 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/dsd64- 怀念.dsf Oct 24 10:15:18 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/dsd64- 南屏晚钟.dsf in service mpd Oct 24 10:15:18 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E7%90%B4/%E9%87%91%E7%89%87%E5%AD%90%20%E8%B4%B0%20%20%E9%AD%82%E8%90%A6%E6%97%A7%E6%A2%A6/extralarge&path=%2Fmnt%2FNAS%2FDSD%2Fdsd64-%20%E5%8D%97%E5%B1%8F%E6%99%9A%E9%92%9F.dsf&metadata=false Oct 24 10:15:18 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/dsd64- 南屏晚钟.dsf Oct 24 10:15:18 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/dsd64- 如果没有你.dsf in service mpd Oct 24 10:15:18 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E7%90%B4/%E9%87%91%E7%89%87%E5%AD%90%20%E8%B4%B0%20%20%E9%AD%82%E8%90%A6%E6%97%A7%E6%A2%A6/extralarge&path=%2Fmnt%2FNAS%2FDSD%2Fdsd64-%20%E5%A6%82%E6%9E%9C%E6%B2%A1%E6%9C%89%E4%BD%A0.dsf&metadata=false Oct 24 10:15:18 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/dsd64- 如果没有你.dsf Oct 24 10:15:18 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD/dsd64- 午夜香吻.dsf in service mpd Oct 24 10:15:18 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=%E8%94%A1%E7%90%B4/%E9%87%91%E7%89%87%E5%AD%90%20%E8%B4%B0%20%20%E9%AD%82%E8%90%A6%E6%97%A7%E6%A2%A6/extralarge&path=%2Fmnt%2FNAS%2FDSD%2Fdsd64-%20%E5%8D%88%E5%A4%9C%E9%A6%99%E5%90%BB.dsf&metadata=false Oct 24 10:15:18 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD/dsd64- 午夜香吻.dsf Oct 24 10:15:19 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 24 10:15:19 volumio-603 volumio[1085]: info: CURURI: music-library/NAS/DSD512 Oct 24 10:15:19 volumio-603 volumio[1085]: info: Preload queue cleared Oct 24 10:15:19 volumio-603 volumio[1085]: info: Preloading song: music-library/NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf Oct 24 10:15:19 volumio-603 volumio[1085]: info: Exploding uri music-library/NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf in service mpd Oct 24 10:15:19 volumio-603 volumio[1085]: info: ALBUMART /albumart?cacheid=862&web=Rachel%20Podger%2C%20Brecon%20Baroque/Le%20Quattro%20Stagioni/extralarge&path=%2Fmnt%2FNAS%2FDSD512%2F3.%20Vivaldi-The%20Four%20Seasons-Winter%2C%20Rachel%20Podger%2C%20Brecon%20Baroque-1.Largo-DSD512.dsf&metadata=false Oct 24 10:15:19 volumio-603 volumio[1085]: info: URI /mnt/NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf Oct 24 10:15:20 volumio-603 volumio[1085]: info: Preload queue cleared Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::ClearQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::stop Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::stPlaybackTimer Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::updateTrackBlock Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrackBlock Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::serviceStop Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 8 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::serviceStop Oct 24 10:15:20 volumio-603 volumio[1085]: info: ControllerMpd::stop Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::clearPlayQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::saveQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::addQueueItems Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::addQueueItems Oct 24 10:15:20 volumio-603 volumio[1085]: info: Preload queue cleared Oct 24 10:15:20 volumio-603 volumio[1085]: info: Adding Item to queue: music-library/NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf Oct 24 10:15:20 volumio-603 volumio[1085]: info: Using cached record of: music-library/NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::saveQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::updateTrackBlock Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrackBlock Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPlay Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::play index 0 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::stop Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::play index undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::startPlaybackTimer Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::clearAddPlayTracks NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf Oct 24 10:15:20 volumio-603 volumio[1085]: info: Setting Volume to 100 automatically for DSD Oct 24 10:15:20 volumio-603 volumio[1085]: info: VolumeController::SetAlsaVolume100 Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: Oct 24 10:15:20 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:20 volumio-603 volumio[1085]: info: sendMpdCommand stop took 163 milliseconds Oct 24 10:15:20 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:20 volumio-603 volumio[1085]: info: sendMpdCommand stop took 49 milliseconds Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 10:15:20 volumio-603 volumio[1085]: info: Oct 24 10:15:20 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:20 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:20 volumio-603 volumio[1085]: info: Oct 24 10:15:20 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:15:20 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:15:20 volumio-603 volumio[1085]: info: sendMpdCommand status took 10 milliseconds Oct 24 10:15:20 volumio-603 volumio[1085]: info: sendMpdCommand clear took 9 milliseconds Oct 24 10:15:20 volumio-603 volumio[1085]: info: sendMpdCommand status took 8 milliseconds Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand add "NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf" Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:20 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: CURRENT POSITION 0 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService stop Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: No code Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:20 volumio-603 volumio[1085]: info: ------------------------------ 93ms Oct 24 10:15:20 volumio-603 volumio[1085]: info: Oct 24 10:15:20 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:15:20 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:15:20 volumio-603 volumio[1085]: error: updateQueue error: null Oct 24 10:15:20 volumio-603 volumio[1085]: info: Oct 24 10:15:20 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:15:20 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:15:20 volumio-603 volumio[1085]: info: ------------------------------ 97ms Oct 24 10:15:20 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 93 milliseconds Oct 24 10:15:20 volumio-603 volumio[1085]: info: sendMpdCommand add "NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf" took 93 milliseconds Oct 24 10:15:20 volumio-603 volumio[1085]: info: ------------------------------ 9ms Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand play Oct 24 10:15:20 volumio-603 volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: ------------------------------ 115ms Oct 24 10:15:20 volumio-603 volumio[1085]: info: Oct 24 10:15:20 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:15:20 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:15:20 volumio-603 volumio[1085]: info: Preload queue cleared Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::ClearQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::stop Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::clearPlayQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::saveQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::addQueueItems Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::addQueueItems Oct 24 10:15:20 volumio-603 volumio[1085]: info: Preload queue cleared Oct 24 10:15:20 volumio-603 volumio[1085]: info: Adding Item to queue: music-library/NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf Oct 24 10:15:20 volumio-603 volumio[1085]: info: Using cached record of: music-library/NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::saveQueue Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::updateTrackBlock Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrackBlock Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPlay Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::play index 0 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::stop Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::play index undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:20 volumio-603 volumio[1085]: info: CoreStateMachine::startPlaybackTimer Oct 24 10:15:20 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:20 volumio-603 volumio[1085]: verbose: ControllerMpd::clearAddPlayTracks NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf Oct 24 10:15:20 volumio-603 volumio[1085]: info: Setting Volume to 100 automatically for DSD Oct 24 10:15:20 volumio-603 volumio[1085]: info: VolumeController::SetAlsaVolume100 Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 301ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand play took 294 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 292ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand stop took 76 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:15:21 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand status took 56 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand status took 56 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand status took 56 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand clear took 55 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand status took 53 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand add "NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf" Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: CURRENT POSITION 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService stop Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: No code Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 131ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:15:21 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:21 volumio-603 volumio[1085]: error: updateQueue error: null Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:15:21 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 104ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 98 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 98 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 98 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand add "NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf" took 97 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 25ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand status took 23 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand play Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:21 volumio-603 volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 10:15:21 volumio-603 volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 10:15:21 volumio-603 volumio[1085]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: CURRENT POSITION 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService stop Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: No code Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 207ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 206ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 207ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 123ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces system playlist update Oct 24 10:15:21 volumio-603 volumio[1085]: info: Ignoring MPD Status Update Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 127ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand play took 119 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 25ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:21 volumio-603 volumio[1085]: info: Oct 24 10:15:21 volumio-603 volumio[1085]: ---------------------------- MPD announces state update: player Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::getState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand status took 38 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand status took 43 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand status took 40 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseState Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12,"duration":136,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"45158 Kbps","isStreaming":false,"title":"Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512","artist":"Rachel Podger, Brecon Baroque","album":"Le Quattro Stagioni","uri":"NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf","trackType":"dsf"} Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: CURRENT POSITION 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 73ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: ControllerMpd::parseTrackInfo Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12,"duration":136,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"45158 Kbps","isStreaming":false,"title":"Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512","artist":"Rachel Podger, Brecon Baroque","album":"Le Quattro Stagioni","uri":"NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf","trackType":"dsf"} Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: CURRENT POSITION 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:15:21 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: ControllerMpd::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::servicePushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12,"duration":136,"samplerate":"22.58 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"45158 Kbps","isStreaming":false,"title":"Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512","artist":"Rachel Podger, Brecon Baroque","album":"Le Quattro Stagioni","uri":"NAS/DSD512/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf","trackType":"dsf"} Oct 24 10:15:21 volumio-603 volumio[1085]: verbose: CURRENT POSITION 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState stateService play Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::syncState currentStatus play Oct 24 10:15:21 volumio-603 volumio[1085]: info: Received an update from plugin. extracting info from payload Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreStateMachine::pushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: CorePlayQueue::getTrack 0 Oct 24 10:15:21 volumio-603 volumio[1085]: info: CoreCommandRouter::volumioPushState Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 172ms Oct 24 10:15:21 volumio-603 volumio[1085]: info: ------------------------------ 166ms Oct 24 10:15:21 volumio-603 volumio[1085]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 10:15:21 volumio-603 volumio[1085]: Error: connect ECONNREFUSED 104.244.46.17:443 Oct 24 10:15:21 volumio-603 volumio[1085]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 24 10:15:21 volumio-603 volumio[1085]: errno: -111, Oct 24 10:15:21 volumio-603 volumio[1085]: code: 'ECONNREFUSED', Oct 24 10:15:21 volumio-603 volumio[1085]: syscall: 'connect', Oct 24 10:15:21 volumio-603 volumio[1085]: address: '104.244.46.17', Oct 24 10:15:21 volumio-603 volumio[1085]: port: 443 Oct 24 10:15:21 volumio-603 volumio[1085]: } Oct 24 10:15:21 volumio-603 volumio[1085]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 10:15:22 volumio-603 sudo[8022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 10:14 Oct 24 10:15:22 volumio-603 sudo[8022]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:15:22 volumio-603 kernel: sched: RT throttling activated 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"