-- Logs begin at Wed 2024-09-18 22:10:22 CST, end at Fri 2024-10-25 20:33:58 CST. -- Oct 25 20:32:03 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState Oct 25 20:32:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:07 volumio volumio[3019]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 20:32:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 20:32:25 volumio volumio[3019]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Oct 25 20:32:25 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Oct 25 20:32:25 volumio sudo[4728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 20:32:25 volumio sudo[4728]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:25 volumio sudo[4728]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:25 volumio volumio[3019]: info: MPD Permissions set Oct 25 20:32:25 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:25 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:25 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:25 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:25 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:25 volumio sudo[4731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 20:32:25 volumio sudo[4731]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:25 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 25 20:32:25 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 25 20:32:25 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 25 20:32:25 volumio systemd[1]: Stopping Music Player Daemon... Oct 25 20:32:25 volumio systemd[1]: mpd.service: Succeeded. Oct 25 20:32:25 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 20:32:25 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:32:25 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 25 20:32:25 volumio systemd[1]: Starting Music Player Daemon... Oct 25 20:32:25 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:26 volumio sudo[4737]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 20:32:26 volumio sudo[4737]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:26 volumio sudo[4737]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:29 volumio volumio[3019]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 25 20:32:29 volumio volumio[3019]: info: Retrying Login Due to Network Error Oct 25 20:32:30 volumio mpd[4742]: Oct 25 20:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 20:32:30 volumio systemd[1]: Started Music Player Daemon. Oct 25 20:32:30 volumio sudo[4731]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:30 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 25 20:32:30 volumio volumio[3019]: error: updateQueue error: null Oct 25 20:32:31 volumio volumio[4789]: cset: --> shielding system active with Oct 25 20:32:31 volumio volumio[4789]: cset: "system" cpuset of CPUSPEC(1) with 234 tasks running Oct 25 20:32:31 volumio volumio[4789]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Oct 25 20:32:31 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 25 20:32:39 volumio volumio[3019]: info: MyVolumio login type: Token Oct 25 20:32:41 volumio startx[1191]: (EE) client bug: timer event2 debounce: offset negative (-249ms) Oct 25 20:32:41 volumio startx[1191]: (EE) client bug: timer event2 debounce: offset negative (-57ms) Oct 25 20:32:41 volumio startx[1191]: (EE) client bug: timer event2 debounce short: offset negative (-70ms) Oct 25 20:32:47 volumio volumio[3019]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 25 20:32:47 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 25 20:32:47 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState Oct 25 20:32:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:47 volumio volumio[3019]: info: Restoring Previous Volume level: 28 false true Oct 25 20:32:47 volumio volumio[3019]: info: VolumeController::SetAlsaVolume100 Oct 25 20:32:47 volumio volumio[3019]: info: Enable softmixer device for audio device number 1,1 Oct 25 20:32:47 volumio volumio[3019]: info: CoreCommandRouter::volumioStop Oct 25 20:32:47 volumio volumio[3019]: info: CoreStateMachine::stop Oct 25 20:32:47 volumio volumio[3019]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:32:47 volumio volumio[3019]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:32:47 volumio volumio[3019]: info: CoreStateMachine::updateTrackBlock Oct 25 20:32:47 volumio volumio[3019]: info: CorePlayQueue::getTrackBlock Oct 25 20:32:47 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:32:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:47 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:32:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:32:47 volumio volumio[3019]: info: CoreStateMachine::serviceStop Oct 25 20:32:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:47 volumio volumio[3019]: info: CoreCommandRouter::serviceStop Oct 25 20:32:47 volumio volumio[3019]: info: ControllerMpd::stop Oct 25 20:32:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:32:47 volumio volumio[3019]: info: Enable softmixer device for audio device undefined Oct 25 20:32:48 volumio volumio[3019]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 25 20:32:48 volumio volumio[3019]: info: Updating Volume Controller Parameters: Device: 1,1 Name: SPDIF Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 25 20:32:48 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 25 20:32:48 volumio volumio[3019]: info: Disabling external Volume Control Oct 25 20:32:48 volumio volumio[3019]: info: Output device has changed, restarting MPD Oct 25 20:32:48 volumio sudo[4817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 20:32:48 volumio sudo[4817]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:48 volumio volumio[3019]: info: Output device has changed, restarting Shairport Sync Oct 25 20:32:48 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:48 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:32:48 volumio sudo[4817]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:48 volumio sudo[4820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 20:32:48 volumio sudo[4820]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:48 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 25 20:32:48 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 25 20:32:48 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 25 20:32:48 volumio systemd[1]: Stopping Music Player Daemon... Oct 25 20:32:48 volumio systemd[1]: mpd.service: Succeeded. Oct 25 20:32:48 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 20:32:48 volumio volumio[3019]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 20:32:48 volumio systemd[1]: Starting Music Player Daemon... Oct 25 20:32:48 volumio volumio[3019]: info: Volume configurations have been set Oct 25 20:32:48 volumio volumio[3019]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 25 20:32:48 volumio volumio[3019]: info: Updating Volume Controller Parameters: Device: 1,1 Name: SPDIF Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 25 20:32:48 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 25 20:32:48 volumio volumio[3019]: info: Disabling external Volume Control Oct 25 20:32:48 volumio sudo[4825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 20:32:48 volumio sudo[4825]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:49 volumio sudo[4825]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:49 volumio volumio[3019]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 20:32:49 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:32:49 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:49 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:32:49 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:32:49 volumio volumio[3019]: error: MPD error: Error: write EPIPE Oct 25 20:32:49 volumio volumio[3019]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Oct 25 20:32:49 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:32:49 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:49 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:32:49 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:32:49 volumio volumio[3019]: info: MPD Permissions set Oct 25 20:32:49 volumio volumio[3019]: info: Software Volume ALSA configuration written Oct 25 20:32:49 volumio volumio[3019]: info: Preparing to generate the ALSA configuration file Oct 25 20:32:50 volumio volumio[3019]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 25 20:32:50 volumio volumio[3019]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 25 20:32:50 volumio volumio[3019]: info: Reading ALSA contributions from plugins. Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:32:50 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 20:32:51 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:51 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:51 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:51 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:51 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:32:51 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 20:32:51 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 20:32:51 volumio volumio[3019]: info: VolumeController:: Volume=100 Mute =false Oct 25 20:32:51 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:32:51 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:51 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:32:51 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:32:51 volumio volumio[3019]: info: Starting Shairport Sync Oct 25 20:32:51 volumio volumio[3019]: info: Asound.conf file written Oct 25 20:32:51 volumio sudo[4878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 20:32:51 volumio sudo[4878]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 20:32:51 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 20:32:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 20:32:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 20:32:51 volumio sudo[4882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 25 20:32:51 volumio sudo[4882]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:51 volumio sudo[4878]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:51 volumio sudo[4882]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:52 volumio volumio[3019]: info: Output device has changed, restarting MPD Oct 25 20:32:52 volumio sudo[4890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 20:32:52 volumio sudo[4890]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:52 volumio volumio[3019]: info: Output device has changed, restarting Shairport Sync Oct 25 20:32:52 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:52 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:32:52 volumio sudo[4890]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:52 volumio sudo[4893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 20:32:52 volumio sudo[4893]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:52 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 25 20:32:52 volumio systemd[1]: mpd.service: Succeeded. Oct 25 20:32:52 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 20:32:52 volumio systemd[1]: Starting Music Player Daemon... Oct 25 20:32:52 volumio volumio[3019]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 20:32:53 volumio sudo[4898]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 20:32:53 volumio sudo[4898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:53 volumio sudo[4898]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:54 volumio volumio[3019]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 25 20:32:55 volumio volumio[3019]: Found hardware: "HDA-Intel" "Realtek ALC892" "HDA:10ec0892,10ec0892,00100302" "0x1002" "0x4383" Oct 25 20:32:55 volumio volumio[3019]: Hardware is initialized using a generic method Oct 25 20:32:55 volumio volumio[3019]: info: Output device has changed, restarting MPD Oct 25 20:32:55 volumio sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 20:32:55 volumio sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:55 volumio volumio[3019]: info: Output device has changed, restarting Shairport Sync Oct 25 20:32:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:32:55 volumio sudo[4906]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:55 volumio sudo[4909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 20:32:55 volumio sudo[4909]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:56 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 25 20:32:56 volumio systemd[1]: mpd.service: Succeeded. Oct 25 20:32:56 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 20:32:56 volumio systemd[1]: Starting Music Player Daemon... Oct 25 20:32:56 volumio volumio[3019]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 20:32:56 volumio volumio[3019]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 25 20:32:56 volumio volumio[3019]: info: Updating Volume Controller Parameters: Device: 1,1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 25 20:32:56 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 25 20:32:56 volumio volumio[3019]: info: Disabling external Volume Control Oct 25 20:32:56 volumio sudo[4915]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 20:32:56 volumio sudo[4915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:56 volumio sudo[4915]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:56 volumio volumio[3019]: info: VolumeController::SetAlsaVolume28 Oct 25 20:32:57 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:32:57 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:32:57 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:32:57 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:32:57 volumio volumio[3019]: info: MPD Permissions set Oct 25 20:32:57 volumio volumio[3019]: info: MPD Permissions set Oct 25 20:32:57 volumio volumio[3019]: info: Shairport-Sync Started Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:58 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:32:58 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 25 20:32:58 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:32:58 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 25 20:32:58 volumio volumio[3019]: info: Starting Shairport Sync Oct 25 20:32:58 volumio sudo[4953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 20:32:58 volumio sudo[4953]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:58 volumio volumio[3019]: info: Starting Shairport Sync Oct 25 20:32:58 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 25 20:32:58 volumio systemd[1]: mpd.service: Succeeded. Oct 25 20:32:58 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 20:32:58 volumio systemd[1]: Starting Music Player Daemon... Oct 25 20:32:58 volumio sudo[4956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 20:32:58 volumio sudo[4956]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 20:32:59 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 20:32:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 20:32:59 volumio sudo[4960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 20:32:59 volumio sudo[4960]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 20:32:59 volumio sudo[4956]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 20:32:59 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 25 20:32:59 volumio volumio[3019]: info: Shairport-Sync Started Oct 25 20:32:59 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 20:32:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 20:32:59 volumio sudo[4957]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 20:32:59 volumio sudo[4957]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:32:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 20:32:59 volumio sudo[4960]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:59 volumio volumio[3019]: info: Shairport-Sync Started Oct 25 20:32:59 volumio sudo[4957]: pam_unix(sudo:session): session closed for user root Oct 25 20:32:59 volumio volumio[3019]: info: VolumeController::SetAlsaVolume53 Oct 25 20:32:59 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:32:59 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:32:59 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:32:59 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:02 volumio volumio[3019]: info: VolumeController::SetAlsaVolume46 Oct 25 20:33:03 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:03 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:33:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:03 volumio mpd[4965]: Oct 25 20:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 20:33:03 volumio systemd[1]: Started Music Player Daemon. Oct 25 20:33:03 volumio sudo[4953]: pam_unix(sudo:session): session closed for user root Oct 25 20:33:03 volumio sudo[4820]: pam_unix(sudo:session): session closed for user root Oct 25 20:33:03 volumio sudo[4893]: pam_unix(sudo:session): session closed for user root Oct 25 20:33:03 volumio sudo[4909]: pam_unix(sudo:session): session closed for user root Oct 25 20:33:03 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 25 20:33:03 volumio volumio[3019]: error: MPD error: The expression evaluated to a falsy value: Oct 25 20:33:03 volumio volumio[3019]: assert.ok(self.idling) Oct 25 20:33:03 volumio volumio[3019]: error: The expression evaluated to a falsy value: Oct 25 20:33:03 volumio volumio[3019]: assert.ok(self.idling) Oct 25 20:33:03 volumio volumio[3019]: error: MPD error: The expression evaluated to a falsy value: Oct 25 20:33:03 volumio volumio[3019]: assert.ok(self.idling) Oct 25 20:33:03 volumio volumio[3019]: error: The expression evaluated to a falsy value: Oct 25 20:33:03 volumio volumio[3019]: assert.ok(self.idling) Oct 25 20:33:03 volumio volumio[3019]: error: MPD error: The expression evaluated to a falsy value: Oct 25 20:33:03 volumio volumio[3019]: assert.ok(self.idling) Oct 25 20:33:03 volumio volumio[3019]: error: The expression evaluated to a falsy value: Oct 25 20:33:03 volumio volumio[3019]: assert.ok(self.idling) Oct 25 20:33:03 volumio volumio[3019]: error: updateQueue error: null Oct 25 20:33:04 volumio volumio[4985]: cset: --> shielding system active with Oct 25 20:33:04 volumio volumio[4985]: cset: "system" cpuset of CPUSPEC(1) with 232 tasks running Oct 25 20:33:04 volumio volumio[4985]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Oct 25 20:33:04 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 25 20:33:08 volumio startx[1191]: (EE) client bug: timer event2 debounce: offset negative (-334ms) Oct 25 20:33:08 volumio startx[1191]: (EE) client bug: timer event2 debounce short: offset negative (-347ms) Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPlay Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::play index undefined Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::clearAddPlayTracks USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand stop took 1 milliseconds Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:09 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand clear took 6 milliseconds Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand add "USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav" Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:09 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:09 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:09 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:09 volumio volumio[3019]: error: updateQueue error: null Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:09 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 17ms Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand add "USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav" took 16 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 15ms Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 10ms Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 8ms Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand play Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:09 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:09 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:09 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 19ms Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand play took 12 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 92ms Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 86ms Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 85ms Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand status took 79 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand status took 5 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand status took 4 milliseconds Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:09 volumio volumio[3019]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 25 20:33:09 volumio volumio[3019]: info: Retrying Login Due to Network Error Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand status took 30 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand status took 29 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand status took 24 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand status took 23 milliseconds Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.突然的自我.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav","trackType":"wav"} Oct 25 20:33:09 volumio volumio[3019]: verbose: CURRENT POSITION 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.突然的自我.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav","trackType":"wav"} Oct 25 20:33:09 volumio volumio[3019]: verbose: CURRENT POSITION 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:09 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.突然的自我.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav","trackType":"wav"} Oct 25 20:33:09 volumio volumio[3019]: verbose: CURRENT POSITION 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:09 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 194ms Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 305ms Oct 25 20:33:09 volumio volumio[3019]: info: ------------------------------ 306ms Oct 25 20:33:09 volumio volumio[3019]: info: Oct 25 20:33:09 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 289 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 291 milliseconds Oct 25 20:33:09 volumio volumio[3019]: info: sendMpdCommand status took 15 milliseconds Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:09 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.突然的自我.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav","trackType":"wav"} Oct 25 20:33:09 volumio volumio[3019]: verbose: CURRENT POSITION 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:09 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.突然的自我.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav","trackType":"wav"} Oct 25 20:33:09 volumio volumio[3019]: verbose: CURRENT POSITION 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:09 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.突然的自我.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav","trackType":"wav"} Oct 25 20:33:09 volumio volumio[3019]: verbose: CURRENT POSITION 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:09 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.突然的自我.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav","trackType":"wav"} Oct 25 20:33:09 volumio volumio[3019]: verbose: CURRENT POSITION 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:09 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:09 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:09 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:09 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:10 volumio volumio[3019]: info: ------------------------------ 984ms Oct 25 20:33:10 volumio volumio[3019]: info: ------------------------------ 979ms Oct 25 20:33:10 volumio volumio[3019]: info: ------------------------------ 979ms Oct 25 20:33:10 volumio volumio[3019]: info: ------------------------------ 980ms Oct 25 20:33:10 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 864 milliseconds Oct 25 20:33:10 volumio volumio[3019]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:33:10 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:10 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:10 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:10 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.突然的自我.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav","trackType":"wav"} Oct 25 20:33:10 volumio volumio[3019]: verbose: CURRENT POSITION 0 Oct 25 20:33:10 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:10 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:10 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:10 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:10 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:10 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:33:10 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:10 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:10 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:10 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:10 volumio volumio[3019]: info: ------------------------------ 1041ms Oct 25 20:33:19 volumio volumio[3019]: info: MyVolumio login type: Token Oct 25 20:33:19 volumio volumio[3019]: info: VolumeController::SetAlsaVolume71 Oct 25 20:33:19 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:19 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:19 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:33:19 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:28 volumio volumio[3019]: info: CoreCommandRouter::volumioNext Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::next Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::stop Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::updateTrackBlock Oct 25 20:33:28 volumio volumio[3019]: info: CorePlayQueue::getTrackBlock Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:28 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:28 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:33:28 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::serviceStop Oct 25 20:33:28 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 Oct 25 20:33:28 volumio volumio[3019]: info: CoreCommandRouter::serviceStop Oct 25 20:33:28 volumio volumio[3019]: info: ControllerMpd::stop Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:33:28 volumio volumio[3019]: info: Oct 25 20:33:28 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:28 volumio volumio[3019]: info: sendMpdCommand stop took 111 milliseconds Oct 25 20:33:28 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::play index undefined Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:33:28 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:33:28 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::clearAddPlayTracks USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::updateTrackBlock Oct 25 20:33:28 volumio volumio[3019]: info: CorePlayQueue::getTrackBlock Oct 25 20:33:28 volumio volumio[3019]: info: sendMpdCommand status took 14 milliseconds Oct 25 20:33:28 volumio volumio[3019]: info: sendMpdCommand stop took 8 milliseconds Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 20:33:28 volumio volumio[3019]: info: Oct 25 20:33:28 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:28 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:28 volumio volumio[3019]: info: Oct 25 20:33:28 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:28 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:28 volumio volumio[3019]: info: Oct 25 20:33:28 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:28 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:28 volumio volumio[3019]: info: Oct 25 20:33:28 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:28 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:28 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 25 20:33:28 volumio volumio[3019]: info: sendMpdCommand clear took 27 milliseconds Oct 25 20:33:28 volumio volumio[3019]: info: sendMpdCommand status took 23 milliseconds Oct 25 20:33:28 volumio volumio[3019]: info: sendMpdCommand status took 17 milliseconds Oct 25 20:33:28 volumio volumio[3019]: info: sendMpdCommand status took 3 milliseconds Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand add "USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav" Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:28 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:28 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:28 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:28 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:28 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:28 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:33:28 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.突然的自我.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/01.突然的自我.wav","trackType":"wav"} Oct 25 20:33:29 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: No code Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: 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 25 20:33:29 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: No code Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: 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 25 20:33:29 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: No code Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: 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 25 20:33:29 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: No code Oct 25 20:33:29 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:29 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:29 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 1214ms Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 1185ms Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 1183ms Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 1178ms Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:29 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:29 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:29 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:29 volumio volumio[3019]: error: updateQueue error: null Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:29 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 1196ms Oct 25 20:33:29 volumio volumio[3019]: info: sendMpdCommand add "USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav" took 1186 milliseconds Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 24ms Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 23ms Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 21ms Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand play Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:29 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:29 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces system playlist update Oct 25 20:33:29 volumio volumio[3019]: info: Ignoring MPD Status Update Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 225ms Oct 25 20:33:29 volumio volumio[3019]: info: sendMpdCommand play took 205 milliseconds Oct 25 20:33:29 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 204ms Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 203ms Oct 25 20:33:29 volumio volumio[3019]: info: ------------------------------ 192ms Oct 25 20:33:29 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:29 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:29 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:29 volumio volumio[3019]: info: Oct 25 20:33:29 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:29 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:29 volumio volumio[3019]: info: sendMpdCommand status took 43 milliseconds Oct 25 20:33:29 volumio volumio[3019]: info: sendMpdCommand status took 42 milliseconds Oct 25 20:33:29 volumio volumio[3019]: info: sendMpdCommand status took 27 milliseconds Oct 25 20:33:29 volumio volumio[3019]: info: sendMpdCommand status took 17 milliseconds Oct 25 20:33:29 volumio volumio[3019]: info: sendMpdCommand status took 15 milliseconds Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:29 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:30 volumio volumio[3019]: info: Oct 25 20:33:30 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:30 volumio volumio[3019]: info: Oct 25 20:33:30 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:30 volumio volumio[3019]: info: Oct 25 20:33:30 volumio volumio[3019]: ---------------------------- MPD announces state update: player Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::getState Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand status took 40 milliseconds Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand status took 24 milliseconds Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand status took 23 milliseconds Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::parseState Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.寂寞沙洲冷.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav","trackType":"wav"} Oct 25 20:33:30 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.寂寞沙洲冷.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav","trackType":"wav"} Oct 25 20:33:30 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:30 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.寂寞沙洲冷.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav","trackType":"wav"} Oct 25 20:33:30 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:30 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.寂寞沙洲冷.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav","trackType":"wav"} Oct 25 20:33:30 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:30 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.寂寞沙洲冷.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav","trackType":"wav"} Oct 25 20:33:30 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:30 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: ------------------------------ 340ms Oct 25 20:33:30 volumio volumio[3019]: info: ------------------------------ 822ms Oct 25 20:33:30 volumio volumio[3019]: info: ------------------------------ 812ms Oct 25 20:33:30 volumio volumio[3019]: info: ------------------------------ 803ms Oct 25 20:33:30 volumio volumio[3019]: info: ------------------------------ 812ms Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 727 milliseconds Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 728 milliseconds Oct 25 20:33:30 volumio volumio[3019]: info: sendMpdCommand playlistinfo took 736 milliseconds Oct 25 20:33:30 volumio volumio[3019]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.寂寞沙洲冷.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav","trackType":"wav"} Oct 25 20:33:30 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:30 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.寂寞沙洲冷.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav","trackType":"wav"} Oct 25 20:33:30 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:30 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: ControllerMpd::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::servicePushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.寂寞沙洲冷.wav","artist":null,"album":null,"uri":"USB/1A73-B7B7/安冬影音试音曲库/A132.樊桐舟 绝色2_音色甜的惊人/02.寂寞沙洲冷.wav","trackType":"wav"} Oct 25 20:33:30 volumio volumio[3019]: verbose: CURRENT POSITION 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState stateService play Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:33:30 volumio volumio[3019]: info: Received an update from plugin. extracting info from payload Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:30 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:30 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:30 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:31 volumio volumio[3019]: info: ------------------------------ 1267ms Oct 25 20:33:31 volumio volumio[3019]: info: ------------------------------ 1270ms Oct 25 20:33:31 volumio volumio[3019]: info: ------------------------------ 1254ms Oct 25 20:33:43 volumio startx[1191]: (EE) client bug: timer event2 debounce: offset negative (-144ms) Oct 25 20:33:49 volumio volumio[3019]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 25 20:33:49 volumio volumio[3019]: info: Retrying Login Due to Network Error Oct 25 20:33:50 volumio startx[1191]: (EE) client bug: timer event2 debounce: offset negative (-294ms) Oct 25 20:33:50 volumio startx[1191]: (EE) client bug: timer event2 debounce: offset negative (-135ms) Oct 25 20:33:50 volumio startx[1191]: (EE) client bug: timer event2 debounce short: offset negative (-148ms) Oct 25 20:33:52 volumio volumio[3019]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 25 20:33:52 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState Oct 25 20:33:52 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:53 volumio volumio[3019]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 25 20:33:53 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 25 20:33:53 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState Oct 25 20:33:53 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:53 volumio volumio[3019]: info: Disable softmixer device for audio device Oct 25 20:33:54 volumio volumio[3019]: info: Output device has changed, restarting MPD Oct 25 20:33:54 volumio sudo[5310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 20:33:54 volumio sudo[5310]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:33:54 volumio sudo[5310]: pam_unix(sudo:session): session closed for user root Oct 25 20:33:54 volumio volumio[3019]: info: Output device has changed, restarting Shairport Sync Oct 25 20:33:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:33:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:33:54 volumio sudo[5313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 20:33:54 volumio sudo[5313]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:33:54 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 25 20:33:54 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 25 20:33:54 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 25 20:33:54 volumio systemd[1]: Stopping Music Player Daemon... Oct 25 20:33:54 volumio systemd[1]: mpd.service: Succeeded. Oct 25 20:33:54 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 20:33:54 volumio systemd[1]: Starting Music Player Daemon... Oct 25 20:33:54 volumio volumio[3019]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 20:33:54 volumio volumio[3019]: info: Restoring Previous Volume level: 71 false false Oct 25 20:33:54 volumio volumio[3019]: info: Volume configurations have been set Oct 25 20:33:54 volumio volumio[3019]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 25 20:33:54 volumio volumio[3019]: info: Updating Volume Controller Parameters: Device: 1,1 Name: SPDIF Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 25 20:33:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 25 20:33:54 volumio volumio[3019]: info: Disabling external Volume Control Oct 25 20:33:54 volumio sudo[5318]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 20:33:54 volumio sudo[5318]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:33:54 volumio sudo[5318]: pam_unix(sudo:session): session closed for user root Oct 25 20:33:54 volumio volumio[3019]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 20:33:55 volumio volumio[3019]: info: Soft Volume ALSA configuration file deleted Oct 25 20:33:55 volumio volumio[3019]: info: Preparing to generate the ALSA configuration file Oct 25 20:33:55 volumio volumio[3019]: info: MPD Permissions set Oct 25 20:33:55 volumio volumio[3019]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 25 20:33:55 volumio volumio[3019]: info: Reading ALSA contributions from plugins. Oct 25 20:33:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:33:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:33:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:33:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:33:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:33:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:33:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:33:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 25 20:33:55 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 20:33:56 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 20:33:56 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:33:56 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 20:33:56 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:33:56 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 20:33:56 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 20:33:56 volumio volumio[3019]: info: VolumeController:: Volume=100 Mute =false Oct 25 20:33:56 volumio volumio[3019]: info: CoreStateMachine::pushState Oct 25 20:33:56 volumio volumio[3019]: info: CorePlayQueue::getTrack 1 Oct 25 20:33:56 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:33:56 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState Oct 25 20:33:56 volumio volumio[3019]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 20:33:56 volumio volumio[3019]: Error: Unable to resolve or reject the same promise twice Oct 25 20:33:56 volumio volumio[3019]: at Promise.reject (/volumio/node_modules/kew/kew.js:189:43) Oct 25 20:33:56 volumio volumio[3019]: at /volumio/app/plugins/miscellanea/albumart/albumart.js:158:15 Oct 25 20:33:56 volumio volumio[3019]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:667:14) Oct 25 20:33:56 volumio volumio[3019]: at ClientRequest.emit (events.js:400:28) Oct 25 20:33:56 volumio volumio[3019]: at TLSSocket.socketErrorListener (_http_client.js:475:9) Oct 25 20:33:56 volumio volumio[3019]: at TLSSocket.emit (events.js:400:28) Oct 25 20:33:56 volumio volumio[3019]: at emitErrorNT (internal/streams/destroy.js:106:8) Oct 25 20:33:56 volumio volumio[3019]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Oct 25 20:33:56 volumio volumio[3019]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 25 20:33:56 volumio volumio[3019]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 20:33:58 volumio sudo[5356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-25 20:32 Oct 25 20:33:58 volumio sudo[5356]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"