-- Logs begin at Wed 2024-05-15 05:42:08 CST, end at Wed 2024-05-15 05:46:57 CST. -- May 15 05:45:02 volumio volumio[829]: info: VolumeController::SetAlsaVolume32 May 15 05:45:02 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:02 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:02 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:02 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:04 volumio volumio[829]: info: VolumeController::SetAlsaVolume0 May 15 05:45:04 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:04 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:04 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:04 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:05 volumio volumio[829]: info: VolumeController::SetAlsaVolume- May 15 05:45:05 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:05 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:05 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:05 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:10 volumio volumio[829]: info: CoreCommandRouter::volumioPause May 15 05:45:10 volumio volumio[829]: info: CoreStateMachine::pause May 15 05:45:10 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer May 15 05:45:10 volumio volumio[829]: info: CoreStateMachine::servicePause May 15 05:45:10 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:10 volumio volumio[829]: info: CoreCommandRouter::servicePause May 15 05:45:10 volumio volumio[829]: info: ControllerMpd::pause May 15 05:45:10 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand pause May 15 05:45:10 volumio volumio[829]: info: May 15 05:45:10 volumio volumio[829]: ---------------------------- MPD announces state update: player May 15 05:45:10 volumio volumio[829]: info: sendMpdCommand pause took 5 milliseconds May 15 05:45:10 volumio volumio[829]: info: ControllerMpd::getState May 15 05:45:10 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status May 15 05:45:10 volumio volumio[829]: info: sendMpdCommand status took 1 milliseconds May 15 05:45:10 volumio volumio[829]: verbose: ControllerMpd::parseState May 15 05:45:10 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 05:45:10 volumio volumio[829]: info: sendMpdCommand playlistinfo took 1 milliseconds May 15 05:45:10 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo May 15 05:45:10 volumio volumio[829]: info: ControllerMpd::pushState May 15 05:45:10 volumio volumio[829]: info: CoreCommandRouter::servicePushState May 15 05:45:10 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:10 volumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24864,"duration":254,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chapter 03","artist":null,"album":"CLAIREMA","uri":"NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Claire Martin-Too Darn Hot (2002) & Secret Love (2004) 24-96 DVDA/Title1 - Chapter 03.wav","trackType":"wav"} May 15 05:45:10 volumio volumio[829]: verbose: CURRENT POSITION 2 May 15 05:45:10 volumio volumio[829]: info: CoreStateMachine::syncState stateService pause May 15 05:45:10 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause May 15 05:45:10 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:10 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:10 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:10 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:10 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer May 15 05:45:10 volumio volumio[829]: info: ------------------------------ 27ms May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 05:45:14 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 05:45:24 volumio volumio[829]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 15 05:45:24 volumio volumio[829]: info: Retrying Login Due to Network Error May 15 05:45:34 volumio volumio[829]: info: MyVolumio login type: Token May 15 05:45:36 volumio volumio[829]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 15 05:45:36 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 15 05:45:36 volumio volumio[829]: info: CoreCommandRouter::volumioGetState May 15 05:45:36 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:36 volumio volumio[829]: info: Restoring Previous Volume level: 0 false false May 15 05:45:36 volumio volumio[829]: info: Volume configurations have been set May 15 05:45:36 volumio volumio[829]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 15 05:45:36 volumio volumio[829]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI 0 Mixer: IEC958 Max Vol: 50 Vol Curve; logarithmic Vol Steps: 1 May 15 05:45:36 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 15 05:45:36 volumio volumio[829]: info: Disabling external Volume Control May 15 05:45:36 volumio volumio[829]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 05:45:36 volumio volumio[829]: Unable to parse: May 15 05:45:36 volumio volumio[829]: Simple mixer control 'IEC958',0 May 15 05:45:36 volumio volumio[829]: Capabilities: pswitch pswitch-joined May 15 05:45:36 volumio volumio[829]: Playback channels: Mono May 15 05:45:36 volumio volumio[829]: Mono: Playback [on] May 15 05:45:36 volumio volumio[829]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 15 05:45:36 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 05:45:36 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:45:36 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 05:45:37 volumio volumio[829]: Unable to parse: May 15 05:45:37 volumio volumio[829]: Simple mixer control 'IEC958',0 May 15 05:45:37 volumio volumio[829]: Capabilities: pswitch pswitch-joined May 15 05:45:37 volumio volumio[829]: Playback channels: Mono May 15 05:45:37 volumio volumio[829]: Mono: Playback [on] May 15 05:45:37 volumio volumio[829]: info: VolumeController:: Volume=undefined Mute =false May 15 05:45:37 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:37 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:37 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:41 volumio volumio[829]: info: VolumeController::SetAlsaVolume0 May 15 05:45:41 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:41 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:41 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:50 volumio volumio[829]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::volumioGetState May 15 05:45:50 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:50 volumio volumio[829]: info: Restoring Previous Volume level: 0 false true May 15 05:45:50 volumio volumio[829]: info: VolumeController::SetAlsaVolume100 May 15 05:45:50 volumio volumio[829]: info: Enable softmixer device for audio device number 0,3 May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::volumioStop May 15 05:45:50 volumio volumio[829]: info: CoreStateMachine::stop May 15 05:45:50 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 05:45:50 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock May 15 05:45:50 volumio volumio[829]: info: CorePlayQueue::getTrackBlock May 15 05:45:50 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer May 15 05:45:50 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:50 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:50 volumio volumio[829]: info: CoreStateMachine::serviceStop May 15 05:45:50 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::serviceStop May 15 05:45:50 volumio volumio[829]: info: ControllerMpd::stop May 15 05:45:50 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop May 15 05:45:50 volumio volumio[829]: info: Enable softmixer device for audio device undefined May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 15 05:45:50 volumio volumio[829]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI 0 Mixer: IEC958 Max Vol: 50 Vol Curve; logarithmic Vol Steps: 1 May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 15 05:45:50 volumio volumio[829]: info: Disabling external Volume Control May 15 05:45:50 volumio volumio[829]: info: Output device has changed, restarting MPD May 15 05:45:50 volumio sudo[1961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 05:45:50 volumio sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:50 volumio volumio[829]: info: Output device has changed, restarting Shairport Sync May 15 05:45:50 volumio sudo[1961]: pam_unix(sudo:session): session closed for user root May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:50 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:45:50 volumio sudo[1964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 05:45:50 volumio sudo[1964]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:50 volumio systemd[1]: Stopping Music Player Daemon... May 15 05:45:50 volumio volumio[829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 05:45:50 volumio volumio[829]: info: Volume configurations have been set May 15 05:45:51 volumio systemd[1]: mpd.service: Succeeded. May 15 05:45:51 volumio systemd[1]: Stopped Music Player Daemon. May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 15 05:45:51 volumio volumio[829]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI 0 Mixer: IEC958 Max Vol: 50 Vol Curve; logarithmic Vol Steps: 1 May 15 05:45:51 volumio systemd[1]: Starting Music Player Daemon... May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 15 05:45:51 volumio volumio[829]: info: Disabling external Volume Control May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 05:45:51 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:51 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:51 volumio volumio[829]: error: MPD error: Error: write EPIPE May 15 05:45:51 volumio volumio[829]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 15 05:45:51 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:51 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:51 volumio volumio[829]: info: MPD Permissions set May 15 05:45:51 volumio volumio[829]: Unable to parse: May 15 05:45:51 volumio volumio[829]: Simple mixer control 'IEC958',0 May 15 05:45:51 volumio volumio[829]: Capabilities: pswitch pswitch-joined May 15 05:45:51 volumio volumio[829]: Playback channels: Mono May 15 05:45:51 volumio volumio[829]: Mono: Playback [on] May 15 05:45:51 volumio volumio[829]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 15 05:45:51 volumio volumio[829]: info: Software Volume ALSA configuration written May 15 05:45:51 volumio volumio[829]: info: Preparing to generate the ALSA configuration file May 15 05:45:51 volumio volumio[829]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 15 05:45:51 volumio volumio[829]: info: Reading ALSA contributions from plugins. May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 05:45:51 volumio volumio[829]: Unable to parse: May 15 05:45:51 volumio volumio[829]: Simple mixer control 'IEC958',0 May 15 05:45:51 volumio volumio[829]: Capabilities: pswitch pswitch-joined May 15 05:45:51 volumio volumio[829]: Playback channels: Mono May 15 05:45:51 volumio volumio[829]: Mono: Playback [on] May 15 05:45:51 volumio volumio[829]: info: VolumeController:: Volume=undefined Mute =false May 15 05:45:51 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:51 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:51 volumio volumio[829]: info: Starting Shairport Sync May 15 05:45:51 volumio volumio[829]: info: Asound.conf file written May 15 05:45:51 volumio sudo[1991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 05:45:51 volumio sudo[1991]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 05:45:51 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 05:45:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 05:45:51 volumio sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 15 05:45:51 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 05:45:51 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root May 15 05:45:51 volumio sudo[1991]: pam_unix(sudo:session): session closed for user root May 15 05:45:51 volumio volumio[829]: info: Output device has changed, restarting MPD May 15 05:45:51 volumio sudo[2002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 05:45:51 volumio sudo[2002]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:51 volumio volumio[829]: info: Output device has changed, restarting Shairport Sync May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:51 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:45:51 volumio sudo[2002]: pam_unix(sudo:session): session closed for user root May 15 05:45:51 volumio sudo[2005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 05:45:51 volumio sudo[2005]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:52 volumio volumio[829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 05:45:52 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 15 05:45:52 volumio systemd[1]: mpd.service: Succeeded. May 15 05:45:52 volumio systemd[1]: Stopped Music Player Daemon. May 15 05:45:52 volumio systemd[1]: Starting Music Player Daemon... May 15 05:45:53 volumio mpd[2010]: May 15 05:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 05:45:53 volumio systemd[1]: Started Music Player Daemon. May 15 05:45:53 volumio sudo[1964]: pam_unix(sudo:session): session closed for user root May 15 05:45:53 volumio sudo[2005]: pam_unix(sudo:session): session closed for user root May 15 05:45:53 volumio volumio[829]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 15 05:45:53 volumio volumio[829]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100500" "0x103c" "0x2187" May 15 05:45:53 volumio volumio[829]: Hardware is initialized using a generic method May 15 05:45:53 volumio volumio[829]: info: Output device has changed, restarting MPD May 15 05:45:53 volumio sudo[2018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 05:45:53 volumio sudo[2018]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:53 volumio volumio[829]: info: Output device has changed, restarting Shairport Sync May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:45:53 volumio sudo[2018]: pam_unix(sudo:session): session closed for user root May 15 05:45:53 volumio sudo[2021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 05:45:53 volumio sudo[2021]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:53 volumio systemd[1]: Stopping Music Player Daemon... May 15 05:45:53 volumio volumio[829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 05:45:53 volumio systemd[1]: mpd.service: Succeeded. May 15 05:45:53 volumio systemd[1]: Stopped Music Player Daemon. May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 15 05:45:53 volumio volumio[829]: info: Updating Volume Controller Parameters: Device: 0,3 Name: softvolume Mixer: SoftMaster Max Vol: 50 Vol Curve; logarithmic Vol Steps: 1 May 15 05:45:53 volumio systemd[1]: Starting Music Player Daemon... May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 15 05:45:53 volumio volumio[829]: info: Disabling external Volume Control May 15 05:45:53 volumio volumio[829]: info: MPD Permissions set May 15 05:45:53 volumio volumio[829]: info: MPD Permissions set May 15 05:45:53 volumio volumio[829]: info: Shairport-Sync Started May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 05:45:53 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:53 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:53 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:53 volumio volumio[829]: info: Starting Shairport Sync May 15 05:45:53 volumio volumio[829]: info: Starting Shairport Sync May 15 05:45:54 volumio sudo[2051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 05:45:54 volumio sudo[2051]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:54 volumio sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 05:45:54 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 05:45:54 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 05:45:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 05:45:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 05:45:54 volumio sudo[2051]: pam_unix(sudo:session): session closed for user root May 15 05:45:54 volumio volumio[829]: info: Shairport-Sync Started May 15 05:45:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 05:45:54 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 15 05:45:54 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 05:45:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 05:45:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 05:45:54 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root May 15 05:45:54 volumio volumio[829]: info: Shairport-Sync Started May 15 05:45:54 volumio mpd[2028]: May 15 05:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 05:45:55 volumio systemd[1]: Started Music Player Daemon. May 15 05:45:55 volumio sudo[2021]: pam_unix(sudo:session): session closed for user root May 15 05:45:55 volumio volumio[829]: error: MPD error: The expression evaluated to a falsy value: May 15 05:45:55 volumio volumio[829]: assert.ok(self.idling) May 15 05:45:55 volumio volumio[829]: error: The expression evaluated to a falsy value: May 15 05:45:55 volumio volumio[829]: assert.ok(self.idling) May 15 05:45:55 volumio volumio[829]: error: updateQueue error: null May 15 05:45:55 volumio volumio[829]: error: updateQueue error: null May 15 05:45:55 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 15 05:45:55 volumio sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 05:45:55 volumio sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 05:45:55 volumio systemd[1]: Stopping Music Player Daemon... May 15 05:45:55 volumio systemd[1]: mpd.service: Succeeded. May 15 05:45:55 volumio systemd[1]: Stopped Music Player Daemon. May 15 05:45:55 volumio systemd[1]: Starting Music Player Daemon... May 15 05:45:55 volumio volumio[829]: info: VolumeController::SetAlsaVolume0 May 15 05:45:55 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:45:55 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:45:55 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:45:55 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:45:56 volumio mpd[2063]: May 15 05:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 05:45:56 volumio systemd[1]: Started Music Player Daemon. May 15 05:45:56 volumio sudo[2061]: pam_unix(sudo:session): session closed for user root May 15 05:45:56 volumio volumio[829]: error: updateQueue error: null May 15 05:46:00 volumio volumio[829]: info: CoreCommandRouter::volumioGetState May 15 05:46:00 volumio volumio[829]: info: CorePlayQueue::getTrack 2 May 15 05:46:04 volumio volumio[829]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 15 05:46:04 volumio volumio[829]: info: Retrying Login Due to Network Error May 15 05:46:10 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 05:46:10 volumio volumio[829]: info: CURURI: albums:// May 15 05:46:10 volumio volumio[829]: info: listAlbums - loading Albums from cache May 15 05:46:10 volumio volumio[829]: info: Preload queue cleared May 15 05:46:14 volumio volumio[829]: info: MyVolumio login type: Token May 15 05:46:30 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 15 05:46:30 volumio volumio[829]: info: CURURI: albums:///STEVIERA May 15 05:46:30 volumio volumio[829]: info: Preload queue cleared May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 02.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 03.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 04.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 05.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 06.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 07.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 08.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 09.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 10.wav May 15 05:46:31 volumio volumio[829]: info: Preloading song: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 11.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2001.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 02.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2002.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 02.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 03.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2003.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 03.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 04.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2004.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 04.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 05.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2005.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 05.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 06.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2006.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 06.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 07.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2007.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 07.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 08.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2008.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 08.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 09.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2009.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 09.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 10.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2010.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 10.wav May 15 05:46:31 volumio volumio[829]: info: Exploding uri music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 11.wav in service mpd May 15 05:46:31 volumio volumio[829]: info: ALBUMART /albumart?cacheid=951&web=/STEVIERA/extralarge&path=%2Fmnt%2FNAS%2FTroyNAS%2F05_SACD%E7%B3%BB%E5%88%97%2FDVD%20AUDIO%20%E9%95%9C%E5%83%8F%EF%BC%88ISO%EF%BC%89%2FStevie%20Ray%20Vaughan%20%26%20Albert%20King-In%20Session-1999%2024-96%20(SACD-DVDA)%2FTitle1%20-%20Chapter%2011.wav&metadata=false May 15 05:46:31 volumio volumio[829]: info: URI /mnt/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 11.wav May 15 05:46:33 volumio volumio[829]: info: Preload queue cleared May 15 05:46:33 volumio volumio[829]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::ClearQueue May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::stop May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 05:46:33 volumio volumio[829]: info: CorePlayQueue::clearPlayQueue May 15 05:46:33 volumio volumio[829]: info: CorePlayQueue::saveQueue May 15 05:46:33 volumio volumio[829]: info: CoreCommandRouter::volumioPushQueue May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::addQueueItems May 15 05:46:33 volumio volumio[829]: info: CorePlayQueue::addQueueItems May 15 05:46:33 volumio volumio[829]: info: Preload queue cleared May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 02.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 02.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 03.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 03.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 04.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 04.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 05.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 05.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 06.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 06.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 07.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 07.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 08.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 08.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 09.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 09.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 10.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 10.wav May 15 05:46:33 volumio volumio[829]: info: Adding Item to queue: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 11.wav May 15 05:46:33 volumio volumio[829]: info: Using cached record of: music-library/NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 11.wav May 15 05:46:33 volumio volumio[829]: info: CoreCommandRouter::volumioPushQueue May 15 05:46:33 volumio volumio[829]: info: CorePlayQueue::saveQueue May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock May 15 05:46:33 volumio volumio[829]: info: CorePlayQueue::getTrackBlock May 15 05:46:33 volumio volumio[829]: info: CoreCommandRouter::volumioPlay May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::play index 0 May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::stop May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::play index undefined May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 05:46:33 volumio volumio[829]: info: CorePlayQueue::getTrack 0 May 15 05:46:33 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer May 15 05:46:33 volumio volumio[829]: info: CorePlayQueue::getTrack 0 May 15 05:46:33 volumio volumio[829]: verbose: ControllerMpd::clearAddPlayTracks NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav May 15 05:46:33 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop May 15 05:46:33 volumio volumio[829]: info: sendMpdCommand stop took 2 milliseconds May 15 05:46:33 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear May 15 05:46:33 volumio volumio[829]: info: May 15 05:46:33 volumio volumio[829]: ---------------------------- MPD announces system playlist update May 15 05:46:33 volumio volumio[829]: info: Ignoring MPD Status Update May 15 05:46:33 volumio volumio[829]: info: sendMpdCommand clear took 4 milliseconds May 15 05:46:33 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand add "NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav" May 15 05:46:33 volumio volumio[829]: error: updateQueue error: null May 15 05:46:33 volumio volumio[829]: info: May 15 05:46:33 volumio volumio[829]: ---------------------------- MPD announces system playlist update May 15 05:46:33 volumio volumio[829]: info: Ignoring MPD Status Update May 15 05:46:33 volumio volumio[829]: info: ------------------------------ 4ms May 15 05:46:33 volumio volumio[829]: info: sendMpdCommand add "NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav" took 2 milliseconds May 15 05:46:33 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play May 15 05:46:33 volumio volumio[829]: info: ------------------------------ 5ms May 15 05:46:33 volumio volumio[829]: info: sendMpdCommand play took 3 milliseconds May 15 05:46:33 volumio volumio[829]: info: May 15 05:46:33 volumio volumio[829]: ---------------------------- MPD announces state update: player May 15 05:46:33 volumio volumio[829]: info: ControllerMpd::getState May 15 05:46:33 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status May 15 05:46:34 volumio volumio[829]: info: May 15 05:46:34 volumio volumio[829]: ---------------------------- MPD announces state update: player May 15 05:46:34 volumio volumio[829]: info: sendMpdCommand status took 43 milliseconds May 15 05:46:34 volumio volumio[829]: info: ControllerMpd::getState May 15 05:46:34 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status May 15 05:46:34 volumio volumio[829]: verbose: ControllerMpd::parseState May 15 05:46:34 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 05:46:34 volumio volumio[829]: info: sendMpdCommand status took 5 milliseconds May 15 05:46:34 volumio volumio[829]: info: sendMpdCommand playlistinfo took 5 milliseconds May 15 05:46:34 volumio volumio[829]: verbose: ControllerMpd::parseState May 15 05:46:34 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 05:46:34 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo May 15 05:46:34 volumio volumio[829]: info: ControllerMpd::pushState May 15 05:46:34 volumio volumio[829]: info: CoreCommandRouter::servicePushState May 15 05:46:34 volumio volumio[829]: info: CorePlayQueue::getTrack 0 May 15 05:46:34 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":540,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chapter 01","artist":null,"album":"STEVIERA","uri":"NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav","trackType":"wav"} May 15 05:46:34 volumio volumio[829]: verbose: CURRENT POSITION 0 May 15 05:46:34 volumio volumio[829]: info: CoreStateMachine::syncState stateService play May 15 05:46:34 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop May 15 05:46:34 volumio volumio[829]: info: ------------------------------ 62ms May 15 05:46:34 volumio volumio[829]: info: sendMpdCommand playlistinfo took 9 milliseconds May 15 05:46:34 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo May 15 05:46:34 volumio volumio[829]: info: ControllerMpd::pushState May 15 05:46:34 volumio volumio[829]: info: CoreCommandRouter::servicePushState May 15 05:46:34 volumio volumio[829]: info: CorePlayQueue::getTrack 0 May 15 05:46:34 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":993,"duration":540,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chapter 01","artist":null,"album":"STEVIERA","uri":"NAS/TroyNAS/05_SACD系列/DVD AUDIO 镜像（ISO）/Stevie Ray Vaughan & Albert King-In Session-1999 24-96 (SACD-DVDA)/Title1 - Chapter 01.wav","trackType":"wav"} May 15 05:46:34 volumio volumio[829]: verbose: CURRENT POSITION 0 May 15 05:46:34 volumio volumio[829]: info: CoreStateMachine::syncState stateService play May 15 05:46:34 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play May 15 05:46:34 volumio volumio[829]: info: Received an update from plugin. extracting info from payload May 15 05:46:34 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:46:34 volumio volumio[829]: info: CorePlayQueue::getTrack 0 May 15 05:46:34 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 05:46:34 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:46:34 volumio volumio[829]: info: CoreStateMachine::pushState May 15 05:46:34 volumio volumio[829]: info: CorePlayQueue::getTrack 0 May 15 05:46:34 volumio volumio[829]: info: CoreCommandRouter::volumioPushState May 15 05:46:34 volumio volumio[829]: info: ------------------------------ 65ms May 15 05:46:44 volumio volumio[829]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 15 05:46:44 volumio volumio[829]: info: Retrying Login Due to Network Error May 15 05:46:54 volumio volumio[829]: info: MyVolumio login type: Token May 15 05:46:56 volumio volumio[829]: info: CoreCommandRouter::volumioGetState May 15 05:46:56 volumio volumio[829]: info: CorePlayQueue::getTrack 0 May 15 05:46:56 volumio volumio[829]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 05:46:56 volumio volumio[829]: Error: connect ETIMEDOUT 31.13.80.54:443 May 15 05:46:56 volumio volumio[829]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 15 05:46:56 volumio volumio[829]: errno: -110, May 15 05:46:56 volumio volumio[829]: code: 'ETIMEDOUT', May 15 05:46:56 volumio volumio[829]: syscall: 'connect', May 15 05:46:56 volumio volumio[829]: address: '31.13.80.54', May 15 05:46:56 volumio volumio[829]: port: 443 May 15 05:46:56 volumio volumio[829]: } May 15 05:46:56 volumio volumio[829]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 05:46:57 volumio sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-15 05:45 May 15 05:46:57 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"