-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Fri 2025-05-23 17:37:08 CST. -- May 23 17:36:01 volumio volumio[1130]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 23 17:36:01 volumio volumio[1130]: info: Preparing to save Alsa Options, stopping services first May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::volumioGetState May 23 17:36:01 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::volumioPause May 23 17:36:01 volumio volumio[1130]: info: CoreStateMachine::pause May 23 17:36:01 volumio volumio[1130]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"HDMI 0"}} May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:01 volumio volumio[1130]: info: Found match in Cards Database: setting mixer for card HDMI 0 May 23 17:36:01 volumio volumio[1130]: info: Device 0 does not have any Mixer Control Available May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 23 17:36:01 volumio volumio[1130]: info: Updating Volume Controller Parameters: Device: 0,0 Name: HDMI 0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 23 17:36:01 volumio volumio[1130]: info: Disabling external Volume Control May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 17:36:01 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:01 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:01 volumio volumio[1130]: info: Preparing to generate the ALSA configuration file May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 17:36:01 volumio volumio[1130]: info: Asound.conf file unchanged, so no further update is needed May 23 17:36:01 volumio volumio[1130]: info: Output device has changed, restarting MPD May 23 17:36:01 volumio sudo[7716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 17:36:01 volumio sudo[7716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:01 volumio sudo[7716]: pam_unix(sudo:session): session closed for user root May 23 17:36:01 volumio volumio[1130]: info: Output device has changed, restarting Shairport Sync May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:01 volumio sudo[7720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 17:36:01 volumio sudo[7720]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:01 volumio systemd[1]: Stopping Music Player Daemon... May 23 17:36:01 volumio volumio[1130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 17:36:01 volumio systemd[1]: mpd.service: Succeeded. May 23 17:36:01 volumio systemd[1]: Stopped Music Player Daemon. May 23 17:36:01 volumio volumio[1130]: info: MPD Permissions set May 23 17:36:01 volumio systemd[1]: Starting Music Player Daemon... May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 17:36:01 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:01 volumio volumio[1130]: info: Starting Shairport Sync May 23 17:36:01 volumio sudo[7725]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 23 17:36:01 volumio sudo[7725]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:01 volumio sudo[7725]: pam_unix(sudo:session): session closed for user root May 23 17:36:01 volumio sudo[7733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 17:36:01 volumio sudo[7733]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 17:36:01 volumio systemd[1]: shairport-sync.service: Succeeded. May 23 17:36:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 17:36:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 17:36:01 volumio sudo[7733]: pam_unix(sudo:session): session closed for user root May 23 17:36:01 volumio volumio[1130]: info: Shairport-Sync Started May 23 17:36:02 volumio mpd[7735]: May 23 17:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 23 17:36:02 volumio systemd[1]: Started Music Player Daemon. May 23 17:36:02 volumio sudo[7720]: pam_unix(sudo:session): session closed for user root May 23 17:36:02 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:05 volumio volumio[1130]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 23 17:36:05 volumio volumio[1130]: info: CoreStateMachine::getcurrentVolume May 23 17:36:05 volumio volumio[1130]: info: CoreCommandRouter::volumioRetrievevolume May 23 17:36:05 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:05 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:05 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:05 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:07 volumio volumio[1130]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 23 17:36:07 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 23 17:36:07 volumio volumio[1130]: info: Preparing to save Alsa Options, stopping services first May 23 17:36:07 volumio volumio[1130]: info: CoreCommandRouter::volumioGetState May 23 17:36:07 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:07 volumio volumio[1130]: info: CoreCommandRouter::volumioPause May 23 17:36:07 volumio volumio[1130]: info: CoreStateMachine::pause May 23 17:36:07 volumio volumio[1130]: info: Saving Audio Output to: {"output_device":{"value":"0,1","label":"HDMI 1"}} May 23 17:36:07 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:07 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:07 volumio volumio[1130]: info: Found match in Cards Database: setting mixer for card HDMI 1 May 23 17:36:07 volumio volumio[1130]: info: Device 0 does not have any Mixer Control Available May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 23 17:36:08 volumio volumio[1130]: info: Updating Volume Controller Parameters: Device: 0,1 Name: HDMI 1 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 23 17:36:08 volumio volumio[1130]: info: Disabling external Volume Control May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 17:36:08 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:08 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:08 volumio volumio[1130]: info: Preparing to generate the ALSA configuration file May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 17:36:08 volumio volumio[1130]: info: Asound.conf file written May 23 17:36:08 volumio sudo[7756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 23 17:36:08 volumio sudo[7756]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:08 volumio sudo[7756]: pam_unix(sudo:session): session closed for user root May 23 17:36:08 volumio volumio[1130]: No state is present for card Audio May 23 17:36:08 volumio volumio[1130]: Found hardware: "HdmiLpeAudio" "" "" "" "" May 23 17:36:08 volumio volumio[1130]: Hardware is initialized using a generic method May 23 17:36:08 volumio volumio[1130]: No state is present for card Audio May 23 17:36:08 volumio volumio[1130]: No state is present for card bytchtes8316 May 23 17:36:08 volumio volumio[1130]: Found hardware: "bytcht-es8316" "" "cfg-spk:2 cfg-mic:in1" "" "" May 23 17:36:08 volumio volumio[1130]: Hardware is initialized using a generic method May 23 17:36:08 volumio volumio[1130]: No state is present for card bytchtes8316 May 23 17:36:08 volumio volumio[1130]: info: Output device has changed, restarting MPD May 23 17:36:08 volumio sudo[7761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 17:36:08 volumio sudo[7761]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:08 volumio sudo[7761]: pam_unix(sudo:session): session closed for user root May 23 17:36:08 volumio volumio[1130]: info: Output device has changed, restarting Shairport Sync May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:08 volumio sudo[7764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 17:36:08 volumio sudo[7764]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:08 volumio systemd[1]: Stopping Music Player Daemon... May 23 17:36:08 volumio volumio[1130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 17:36:08 volumio volumio[1130]: info: Output device has changed, restarting MPD May 23 17:36:08 volumio systemd[1]: mpd.service: Succeeded. May 23 17:36:08 volumio systemd[1]: Stopped Music Player Daemon. May 23 17:36:08 volumio systemd[1]: Starting Music Player Daemon... May 23 17:36:08 volumio sudo[7771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 17:36:08 volumio sudo[7771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:08 volumio volumio[1130]: info: Output device has changed, restarting Shairport Sync May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:08 volumio sudo[7771]: pam_unix(sudo:session): session closed for user root May 23 17:36:08 volumio sudo[7774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 17:36:08 volumio sudo[7774]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 23 17:36:08 volumio systemd[1]: mpd.service: Succeeded. May 23 17:36:08 volumio systemd[1]: Stopped Music Player Daemon. May 23 17:36:08 volumio volumio[1130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 17:36:08 volumio volumio[1130]: info: MPD Permissions set May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio systemd[1]: Starting Music Player Daemon... May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 17:36:08 volumio sudo[7779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 23 17:36:08 volumio sudo[7779]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:08 volumio sudo[7779]: pam_unix(sudo:session): session closed for user root May 23 17:36:08 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:08 volumio volumio[1130]: info: Starting Shairport Sync May 23 17:36:08 volumio volumio[1130]: info: Starting Shairport Sync May 23 17:36:08 volumio sudo[7795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 17:36:08 volumio sudo[7795]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 17:36:08 volumio sudo[7798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 17:36:08 volumio sudo[7798]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:08 volumio systemd[1]: shairport-sync.service: Succeeded. May 23 17:36:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 17:36:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 17:36:08 volumio sudo[7795]: pam_unix(sudo:session): session closed for user root May 23 17:36:08 volumio volumio[1130]: info: Shairport-Sync Started May 23 17:36:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 17:36:08 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 23 17:36:08 volumio systemd[1]: shairport-sync.service: Succeeded. May 23 17:36:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 17:36:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 17:36:08 volumio sudo[7798]: pam_unix(sudo:session): session closed for user root May 23 17:36:08 volumio volumio[1130]: info: Shairport-Sync Started May 23 17:36:09 volumio mpd[7792]: May 23 17:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 23 17:36:09 volumio systemd[1]: Started Music Player Daemon. May 23 17:36:09 volumio sudo[7764]: pam_unix(sudo:session): session closed for user root May 23 17:36:09 volumio sudo[7774]: pam_unix(sudo:session): session closed for user root May 23 17:36:09 volumio volumio[1130]: error: MPD error: The expression evaluated to a falsy value: May 23 17:36:09 volumio volumio[1130]: assert.ok(self.idling) May 23 17:36:09 volumio volumio[1130]: error: The expression evaluated to a falsy value: May 23 17:36:09 volumio volumio[1130]: assert.ok(self.idling) May 23 17:36:09 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::volumioPlay May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 口琴别恋.flac May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand stop took 2 milliseconds May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:10 volumio volumio[1130]: info: May 23 17:36:10 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:10 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand clear took 2 milliseconds May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" May 23 17:36:10 volumio volumio[1130]: info: May 23 17:36:10 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:10 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:10 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:10 volumio volumio[1130]: info: May 23 17:36:10 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:10 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:10 volumio volumio[1130]: info: ------------------------------ 6ms May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" took 5 milliseconds May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:10 volumio volumio[1130]: info: May 23 17:36:10 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:10 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:10 volumio volumio[1130]: info: ------------------------------ 9ms May 23 17:36:10 volumio volumio[1130]: info: ------------------------------ 6ms May 23 17:36:10 volumio volumio[1130]: info: May 23 17:36:10 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand play took 5 milliseconds May 23 17:36:10 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:10 volumio volumio[1130]: info: ------------------------------ 4ms May 23 17:36:10 volumio volumio[1130]: info: May 23 17:36:10 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:10 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:10 volumio volumio[1130]: info: May 23 17:36:10 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:10 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand status took 16 milliseconds May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand status took 15 milliseconds May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand status took 15 milliseconds May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:10 volumio volumio[1130]: info: May 23 17:36:10 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:10 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand clearerror took 16 milliseconds May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand clearerror took 15 milliseconds May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 5 milliseconds May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:10 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:10 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:10 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:10 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:10 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:10 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:10 volumio volumio[1130]: info: ------------------------------ 73ms May 23 17:36:10 volumio volumio[1130]: info: ------------------------------ 71ms May 23 17:36:10 volumio volumio[1130]: info: ------------------------------ 70ms May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand status took 47 milliseconds May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:10 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 17:36:10 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:10 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:10 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:10 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:10 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:10 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:10 volumio volumio[1130]: info: ------------------------------ 62ms May 23 17:36:12 volumio volumio[1130]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 23 17:36:12 volumio volumio[1130]: info: CoreStateMachine::getcurrentVolume May 23 17:36:12 volumio volumio[1130]: info: CoreCommandRouter::volumioRetrievevolume May 23 17:36:12 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:12 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:12 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:12 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:15 volumio volumio[1130]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 23 17:36:15 volumio volumio[1130]: info: Preparing to save Alsa Options, stopping services first May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::volumioGetState May 23 17:36:15 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::volumioPause May 23 17:36:15 volumio volumio[1130]: info: CoreStateMachine::pause May 23 17:36:15 volumio volumio[1130]: info: Saving Audio Output to: {"output_device":{"value":"0,2","label":"HDMI 2"}} May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:15 volumio volumio[1130]: info: Found match in Cards Database: setting mixer for card HDMI 2 May 23 17:36:15 volumio volumio[1130]: info: Device 0 does not have any Mixer Control Available May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 23 17:36:15 volumio volumio[1130]: info: Updating Volume Controller Parameters: Device: 0,2 Name: HDMI 2 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 23 17:36:15 volumio volumio[1130]: info: Disabling external Volume Control May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 17:36:15 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:15 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:15 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:16 volumio volumio[1130]: info: Preparing to generate the ALSA configuration file May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 17:36:16 volumio volumio[1130]: info: Asound.conf file written May 23 17:36:16 volumio sudo[7839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 23 17:36:16 volumio sudo[7839]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:16 volumio sudo[7839]: pam_unix(sudo:session): session closed for user root May 23 17:36:16 volumio volumio[1130]: No state is present for card Audio May 23 17:36:16 volumio volumio[1130]: Found hardware: "HdmiLpeAudio" "" "" "" "" May 23 17:36:16 volumio volumio[1130]: Hardware is initialized using a generic method May 23 17:36:16 volumio volumio[1130]: No state is present for card Audio May 23 17:36:16 volumio volumio[1130]: No state is present for card bytchtes8316 May 23 17:36:16 volumio volumio[1130]: Found hardware: "bytcht-es8316" "" "cfg-spk:2 cfg-mic:in1" "" "" May 23 17:36:16 volumio volumio[1130]: Hardware is initialized using a generic method May 23 17:36:16 volumio volumio[1130]: No state is present for card bytchtes8316 May 23 17:36:16 volumio volumio[1130]: info: Output device has changed, restarting MPD May 23 17:36:16 volumio sudo[7844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 17:36:16 volumio sudo[7844]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:16 volumio volumio[1130]: info: Output device has changed, restarting Shairport Sync May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:16 volumio sudo[7844]: pam_unix(sudo:session): session closed for user root May 23 17:36:16 volumio sudo[7847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 17:36:16 volumio sudo[7847]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:16 volumio systemd[1]: Stopping Music Player Daemon... May 23 17:36:16 volumio volumio[1130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 17:36:16 volumio volumio[1130]: info: Output device has changed, restarting MPD May 23 17:36:16 volumio systemd[1]: mpd.service: Succeeded. May 23 17:36:16 volumio systemd[1]: Stopped Music Player Daemon. May 23 17:36:16 volumio systemd[1]: Starting Music Player Daemon... May 23 17:36:16 volumio sudo[7854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 17:36:16 volumio sudo[7854]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:16 volumio volumio[1130]: info: Output device has changed, restarting Shairport Sync May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:16 volumio sudo[7854]: pam_unix(sudo:session): session closed for user root May 23 17:36:16 volumio sudo[7857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 17:36:16 volumio sudo[7857]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 23 17:36:16 volumio systemd[1]: mpd.service: Succeeded. May 23 17:36:16 volumio systemd[1]: Stopped Music Player Daemon. May 23 17:36:16 volumio volumio[1130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 17:36:16 volumio volumio[1130]: info: MPD Permissions set May 23 17:36:16 volumio volumio[1130]: info: MPD Permissions set May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio systemd[1]: Starting Music Player Daemon... May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 17:36:16 volumio sudo[7862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 23 17:36:16 volumio sudo[7862]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:16 volumio sudo[7862]: pam_unix(sudo:session): session closed for user root May 23 17:36:16 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:16 volumio volumio[1130]: info: Starting Shairport Sync May 23 17:36:16 volumio volumio[1130]: info: Starting Shairport Sync May 23 17:36:16 volumio sudo[7878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 17:36:16 volumio sudo[7878]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 17:36:16 volumio sudo[7881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 17:36:16 volumio sudo[7881]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:16 volumio systemd[1]: shairport-sync.service: Succeeded. May 23 17:36:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 17:36:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 17:36:16 volumio sudo[7878]: pam_unix(sudo:session): session closed for user root May 23 17:36:16 volumio volumio[1130]: info: Shairport-Sync Started May 23 17:36:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 17:36:16 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 23 17:36:16 volumio systemd[1]: shairport-sync.service: Succeeded. May 23 17:36:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 17:36:16 volumio volumio[1130]: (node:1130) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 17:36:16 volumio volumio[1130]: (Use `node --trace-warnings ...` to show where the warning was created) May 23 17:36:16 volumio volumio[1130]: (node:1130) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 17:36:16 volumio volumio[1130]: (node:1130) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 17:36:16 volumio volumio[1130]: (node:1130) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 17:36:16 volumio volumio[1130]: (node:1130) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 17:36:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 17:36:16 volumio sudo[7881]: pam_unix(sudo:session): session closed for user root May 23 17:36:16 volumio volumio[1130]: info: Shairport-Sync Started May 23 17:36:17 volumio mpd[7876]: May 23 17:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 23 17:36:17 volumio systemd[1]: Started Music Player Daemon. May 23 17:36:17 volumio sudo[7857]: pam_unix(sudo:session): session closed for user root May 23 17:36:17 volumio sudo[7847]: pam_unix(sudo:session): session closed for user root May 23 17:36:17 volumio volumio[1130]: error: MPD error: The expression evaluated to a falsy value: May 23 17:36:17 volumio volumio[1130]: assert.ok(self.idling) May 23 17:36:17 volumio volumio[1130]: error: The expression evaluated to a falsy value: May 23 17:36:17 volumio volumio[1130]: assert.ok(self.idling) May 23 17:36:17 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::volumioPlay May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 口琴别恋.flac May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand stop took 2 milliseconds May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:18 volumio volumio[1130]: info: May 23 17:36:18 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:18 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand clear took 5 milliseconds May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" May 23 17:36:18 volumio volumio[1130]: info: May 23 17:36:18 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:18 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:18 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:18 volumio volumio[1130]: info: May 23 17:36:18 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:18 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:18 volumio volumio[1130]: info: ------------------------------ 12ms May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" took 10 milliseconds May 23 17:36:18 volumio volumio[1130]: info: ------------------------------ 9ms May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:18 volumio volumio[1130]: info: May 23 17:36:18 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:18 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:18 volumio volumio[1130]: info: May 23 17:36:18 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:18 volumio volumio[1130]: info: ------------------------------ 11ms May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand play took 6 milliseconds May 23 17:36:18 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:18 volumio volumio[1130]: info: ------------------------------ 5ms May 23 17:36:18 volumio volumio[1130]: info: May 23 17:36:18 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:18 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand status took 22 milliseconds May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:18 volumio volumio[1130]: info: May 23 17:36:18 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:18 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand status took 33 milliseconds May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand clearerror took 14 milliseconds May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 12 milliseconds May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:18 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:18 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:18 volumio volumio[1130]: info: ------------------------------ 64ms May 23 17:36:18 volumio volumio[1130]: info: May 23 17:36:18 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:18 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand status took 42 milliseconds May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand clearerror took 35 milliseconds May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 32 milliseconds May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand status took 6 milliseconds May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:18 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:18 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:18 volumio volumio[1130]: info: ------------------------------ 89ms May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 19 milliseconds May 23 17:36:18 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 18 milliseconds May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:18 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:18 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:18 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:18 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:18 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:18 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:18 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:18 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:18 volumio volumio[1130]: info: ------------------------------ 94ms May 23 17:36:18 volumio volumio[1130]: info: ------------------------------ 57ms May 23 17:36:19 volumio volumio[1130]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 23 17:36:19 volumio volumio[1130]: info: CoreStateMachine::getcurrentVolume May 23 17:36:19 volumio volumio[1130]: info: CoreCommandRouter::volumioRetrievevolume May 23 17:36:19 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:19 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:19 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:19 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:19 volumio volumio[1130]: info: CoreCommandRouter::volumioPlay May 23 17:36:19 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:19 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:19 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:19 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:19 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:19 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 口琴别恋.flac May 23 17:36:19 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand stop took 11 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 6 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clear took 7 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 9 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 8ms May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 5 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" took 6 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 5ms May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: 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} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: No code May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 56ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 60ms May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 50ms May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand play took 47 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 12ms May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 13 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 17 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 11 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 9 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 8 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 44ms May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 21 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 19 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 19 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 66ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 56ms May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPlay May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 口琴别恋.flac May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand stop took 5 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 6 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clear took 5 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 11 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 9ms May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 7 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" took 7 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 6ms May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: 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} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: No code May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 77ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 81ms May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 72ms May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand play took 58 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 5ms May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 15 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 16 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 15 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 14 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 13 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 12 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 62ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 63ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 60ms May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPlay May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 口琴别恋.flac May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand stop took 5 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 4 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clear took 3 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 7 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 6ms May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" took 4 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 4ms May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: 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} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: No code May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 52ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 55ms May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 49ms May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 4ms May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 12 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 11 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 10 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 9 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 45ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 44ms May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPlay May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 口琴别恋.flac May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand stop took 3 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 3 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clear took 3 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 12 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 12ms May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 11 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" took 11 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 10ms May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: 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} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: No code May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 49ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 51ms May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:20 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 48ms May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand play took 38 milliseconds May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 5ms May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: info: May 23 17:36:20 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 14 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 14 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand status took 13 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 14 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 13 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand clearerror took 13 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 17:36:20 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 17:36:20 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:20 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:20 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:20 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:20 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 72ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 72ms May 23 17:36:20 volumio volumio[1130]: info: ------------------------------ 69ms May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPlay May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 口琴别恋.flac May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand stop took 3 milliseconds May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:21 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 3 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand clear took 3 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 2 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: 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} May 23 17:36:21 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState stateService stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: No code May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 33ms May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:21 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:21 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:21 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 34ms May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 33 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" took 33 milliseconds May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 3ms May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:21 volumio volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 41ms May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:21 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 15ms May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand play took 13 milliseconds May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 13ms May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 9 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 10 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 21 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand clearerror took 14 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand clearerror took 14 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 9 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 8 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:21 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:21 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 49ms May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 49ms May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand clearerror took 30 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 28 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:21 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 64ms May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPlay May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 口琴别恋.flac May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand stop took 4 milliseconds May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:21 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 2 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand clear took 2 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:21 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:21 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:21 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 5 milliseconds May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 4ms May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" took 3 milliseconds May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 2ms May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: 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} May 23 17:36:21 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState stateService stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: No code May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 51ms May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 53ms May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:21 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 51ms May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand play took 49 milliseconds May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 4ms May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: info: May 23 17:36:21 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 13 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 13 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand status took 12 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand clearerror took 17 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand clearerror took 16 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand clearerror took 15 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 17:36:21 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:21 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:21 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:21 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:21 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:21 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:21 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:21 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 71ms May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 70ms May 23 17:36:21 volumio volumio[1130]: info: ------------------------------ 68ms May 23 17:36:54 volumio volumio[1130]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 23 17:36:54 volumio volumio[1130]: info: Preparing to save Alsa Options, stopping services first May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::volumioGetState May 23 17:36:54 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::volumioPause May 23 17:36:54 volumio volumio[1130]: info: CoreStateMachine::pause May 23 17:36:54 volumio volumio[1130]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphone Jack"}} May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:54 volumio volumio[1130]: info: Found match in Cards Database: setting mixer HP for card Headphone Jack May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 23 17:36:54 volumio volumio[1130]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphone Jack Mixer: HP Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 23 17:36:54 volumio volumio[1130]: info: Disabling external Volume Control May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 17:36:54 volumio volumio[1130]: info: Preparing to generate the ALSA configuration file May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 17:36:54 volumio volumio[1130]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HP',0 May 23 17:36:54 volumio volumio[1130]: info: Asound.conf file written May 23 17:36:54 volumio sudo[8017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 23 17:36:54 volumio sudo[8017]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:54 volumio sudo[8017]: pam_unix(sudo:session): session closed for user root May 23 17:36:54 volumio volumio[1130]: No state is present for card Audio May 23 17:36:54 volumio volumio[1130]: Found hardware: "HdmiLpeAudio" "" "" "" "" May 23 17:36:54 volumio volumio[1130]: Hardware is initialized using a generic method May 23 17:36:54 volumio volumio[1130]: No state is present for card Audio May 23 17:36:54 volumio volumio[1130]: No state is present for card bytchtes8316 May 23 17:36:54 volumio volumio[1130]: Found hardware: "bytcht-es8316" "" "cfg-spk:2 cfg-mic:in1" "" "" May 23 17:36:54 volumio volumio[1130]: Hardware is initialized using a generic method May 23 17:36:54 volumio volumio[1130]: No state is present for card bytchtes8316 May 23 17:36:54 volumio volumio[1130]: info: Output device has changed, restarting MPD May 23 17:36:54 volumio sudo[8022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 17:36:54 volumio sudo[8022]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:54 volumio sudo[8022]: pam_unix(sudo:session): session closed for user root May 23 17:36:54 volumio volumio[1130]: info: Output device has changed, restarting Shairport Sync May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:54 volumio sudo[8025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 17:36:54 volumio sudo[8025]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:54 volumio systemd[1]: Stopping Music Player Daemon... May 23 17:36:54 volumio volumio[1130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 17:36:54 volumio volumio[1130]: info: Output device has changed, restarting MPD May 23 17:36:54 volumio systemd[1]: mpd.service: Succeeded. May 23 17:36:54 volumio systemd[1]: Stopped Music Player Daemon. May 23 17:36:54 volumio systemd[1]: Starting Music Player Daemon... May 23 17:36:54 volumio sudo[8032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 17:36:54 volumio volumio[1130]: info: Output device has changed, restarting Shairport Sync May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio sudo[8032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:54 volumio sudo[8032]: pam_unix(sudo:session): session closed for user root May 23 17:36:54 volumio sudo[8034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 17:36:54 volumio sudo[8034]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:54 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 23 17:36:54 volumio systemd[1]: mpd.service: Succeeded. May 23 17:36:54 volumio systemd[1]: Stopped Music Player Daemon. May 23 17:36:54 volumio systemd[1]: Starting Music Player Daemon... May 23 17:36:54 volumio volumio[1130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 17:36:54 volumio volumio[1130]: info: MPD Permissions set May 23 17:36:54 volumio volumio[1130]: info: MPD Permissions set May 23 17:36:54 volumio volumio[1130]: info: VolumeController:: Volume=undefined Mute =false May 23 17:36:54 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:54 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 17:36:54 volumio sudo[8040]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 23 17:36:54 volumio sudo[8040]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio sudo[8040]: pam_unix(sudo:session): session closed for user root May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 17:36:54 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:36:54 volumio volumio[1130]: info: Starting Shairport Sync May 23 17:36:54 volumio volumio[1130]: info: Starting Shairport Sync May 23 17:36:54 volumio sudo[8056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 17:36:54 volumio sudo[8056]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:54 volumio sudo[8058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 17:36:54 volumio sudo[8058]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:36:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 17:36:55 volumio systemd[1]: shairport-sync.service: Succeeded. May 23 17:36:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 17:36:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 17:36:55 volumio sudo[8056]: pam_unix(sudo:session): session closed for user root May 23 17:36:55 volumio volumio[1130]: info: Shairport-Sync Started May 23 17:36:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 17:36:55 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 23 17:36:55 volumio systemd[1]: shairport-sync.service: Succeeded. May 23 17:36:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 17:36:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 17:36:55 volumio sudo[8058]: pam_unix(sudo:session): session closed for user root May 23 17:36:55 volumio volumio[1130]: info: Shairport-Sync Started May 23 17:36:55 volumio mpd[8048]: May 23 17:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 23 17:36:55 volumio systemd[1]: Started Music Player Daemon. May 23 17:36:55 volumio sudo[8034]: pam_unix(sudo:session): session closed for user root May 23 17:36:55 volumio sudo[8025]: pam_unix(sudo:session): session closed for user root May 23 17:36:55 volumio volumio[1130]: error: MPD error: The expression evaluated to a falsy value: May 23 17:36:55 volumio volumio[1130]: assert.ok(self.idling) May 23 17:36:55 volumio volumio[1130]: error: The expression evaluated to a falsy value: May 23 17:36:55 volumio volumio[1130]: assert.ok(self.idling) May 23 17:36:55 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::volumioPlay May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 口琴别恋.flac May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand stop took 1 milliseconds May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:57 volumio volumio[1130]: info: May 23 17:36:57 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:57 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand clear took 2 milliseconds May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" May 23 17:36:57 volumio volumio[1130]: info: May 23 17:36:57 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:57 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:57 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:57 volumio volumio[1130]: info: May 23 17:36:57 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:57 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:57 volumio volumio[1130]: info: ------------------------------ 8ms May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 口琴别恋.flac" took 7 milliseconds May 23 17:36:57 volumio volumio[1130]: info: ------------------------------ 8ms May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:57 volumio volumio[1130]: info: May 23 17:36:57 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:57 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:57 volumio volumio[1130]: info: ------------------------------ 8ms May 23 17:36:57 volumio volumio[1130]: info: May 23 17:36:57 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand play took 3 milliseconds May 23 17:36:57 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:57 volumio volumio[1130]: info: ------------------------------ 3ms May 23 17:36:57 volumio volumio[1130]: info: May 23 17:36:57 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:57 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:57 volumio volumio[1130]: info: May 23 17:36:57 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand status took 124 milliseconds May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand status took 125 milliseconds May 23 17:36:57 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:57 volumio volumio[1130]: info: May 23 17:36:57 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:57 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand clearerror took 21 milliseconds May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand clearerror took 19 milliseconds May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand status took 18 milliseconds May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 5 milliseconds May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:57 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:57 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:57 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:57 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:57 volumio volumio[1130]: info: ------------------------------ 188ms May 23 17:36:57 volumio volumio[1130]: info: ------------------------------ 186ms May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand status took 48 milliseconds May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 46 milliseconds May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:57 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:57 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:57 volumio volumio[1130]: info: ------------------------------ 96ms May 23 17:36:57 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 14 milliseconds May 23 17:36:57 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:57 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"口琴别恋","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 口琴别恋.flac","trackType":"flac"} May 23 17:36:57 volumio volumio[1130]: verbose: CURRENT POSITION 6 May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:57 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:57 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:57 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:57 volumio volumio[1130]: info: ------------------------------ 79ms May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::getcurrentVolume May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::volumioRetrievevolume May 23 17:36:58 volumio volumio[1130]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HP',0 May 23 17:36:58 volumio volumio[1130]: info: VolumeController:: Volume=undefined Mute =false May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::updateTrackBlock May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrackBlock May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::volumioRetrievevolume May 23 17:36:58 volumio volumio[1130]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HP',0 May 23 17:36:58 volumio volumio[1130]: info: VolumeController:: Volume=undefined Mute =false May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrack 6 May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::volumioNext May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::next May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::stop May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::play index undefined May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::startPlaybackTimer May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks USB/2924-E37B/刘德华 - 谁人知.flac May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::updateTrackBlock May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrackBlock May 23 17:36:58 volumio volumio[1130]: info: May 23 17:36:58 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:58 volumio volumio[1130]: info: sendMpdCommand stop took 6 milliseconds May 23 17:36:58 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear May 23 17:36:58 volumio volumio[1130]: info: May 23 17:36:58 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:58 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:58 volumio volumio[1130]: info: May 23 17:36:58 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:58 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:58 volumio volumio[1130]: info: sendMpdCommand status took 7 milliseconds May 23 17:36:58 volumio volumio[1130]: info: sendMpdCommand clear took 8 milliseconds May 23 17:36:58 volumio volumio[1130]: info: sendMpdCommand status took 5 milliseconds May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "USB/2924-E37B/刘德华 - 谁人知.flac" May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:58 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:58 volumio volumio[1130]: 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} May 23 17:36:58 volumio volumio[1130]: verbose: CURRENT POSITION 7 May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::syncState stateService stop May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:58 volumio volumio[1130]: info: No code May 23 17:36:58 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:58 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:58 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:58 volumio volumio[1130]: info: ------------------------------ 37ms May 23 17:36:58 volumio volumio[1130]: info: May 23 17:36:58 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:58 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:58 volumio volumio[1130]: error: updateQueue error: null May 23 17:36:58 volumio volumio[1130]: info: May 23 17:36:58 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:58 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:58 volumio volumio[1130]: info: ------------------------------ 37ms May 23 17:36:58 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 33 milliseconds May 23 17:36:58 volumio volumio[1130]: info: sendMpdCommand add "USB/2924-E37B/刘德华 - 谁人知.flac" took 34 milliseconds May 23 17:36:58 volumio volumio[1130]: info: ------------------------------ 4ms May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand play May 23 17:36:58 volumio volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 17:36:58 volumio volumio[1130]: info: ------------------------------ 49ms May 23 17:36:58 volumio volumio[1130]: info: May 23 17:36:58 volumio volumio[1130]: ---------------------------- MPD announces system playlist update May 23 17:36:58 volumio volumio[1130]: info: Ignoring MPD Status Update May 23 17:36:58 volumio volumio[1130]: info: May 23 17:36:58 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:58 volumio volumio[1130]: info: May 23 17:36:58 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:58 volumio volumio[1130]: info: ------------------------------ 11ms May 23 17:36:58 volumio volumio[1130]: info: sendMpdCommand play took 9 milliseconds May 23 17:36:58 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:58 volumio volumio[1130]: info: ------------------------------ 8ms May 23 17:36:58 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:58 volumio volumio[1130]: info: May 23 17:36:58 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:58 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:58 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand status took 28 milliseconds May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand status took 28 milliseconds May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:59 volumio volumio[1130]: info: May 23 17:36:59 volumio volumio[1130]: ---------------------------- MPD announces state update: player May 23 17:36:59 volumio volumio[1130]: info: ControllerMpd::getState May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand status May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand status took 43 milliseconds May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand clearerror took 30 milliseconds May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand clearerror took 18 milliseconds May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 10 milliseconds May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 9 milliseconds May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand status took 6 milliseconds May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::parseState May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 17:36:59 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:59 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:59 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:59 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"谁人知","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 谁人知.flac","trackType":"flac"} May 23 17:36:59 volumio volumio[1130]: verbose: CURRENT POSITION 7 May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:59 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:59 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:59 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:59 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:59 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:59 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"谁人知","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 谁人知.flac","trackType":"flac"} May 23 17:36:59 volumio volumio[1130]: verbose: CURRENT POSITION 7 May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:59 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:59 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:59 volumio volumio[1130]: info: ------------------------------ 70ms May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand clearerror took 29 milliseconds May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 26 milliseconds May 23 17:36:59 volumio volumio[1130]: info: sendMpdCommand playlistinfo took 26 milliseconds May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:59 volumio volumio[1130]: verbose: ControllerMpd::parseTrackInfo May 23 17:36:59 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:59 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:59 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:59 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"谁人知","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 谁人知.flac","trackType":"flac"} May 23 17:36:59 volumio volumio[1130]: verbose: CURRENT POSITION 7 May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:59 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:59 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:59 volumio volumio[1130]: info: ControllerMpd::pushState May 23 17:36:59 volumio volumio[1130]: info: CoreCommandRouter::servicePushState May 23 17:36:59 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:59 volumio volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"谁人知","artist":"刘德华","album":"5时30分","uri":"USB/2924-E37B/刘德华 - 谁人知.flac","trackType":"flac"} May 23 17:36:59 volumio volumio[1130]: verbose: CURRENT POSITION 7 May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::syncState stateService pause May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::syncState currentStatus stop May 23 17:36:59 volumio volumio[1130]: info: CoreStateMachine::pushState May 23 17:36:59 volumio volumio[1130]: info: CorePlayQueue::getTrack 7 May 23 17:36:59 volumio volumio[1130]: info: CoreCommandRouter::volumioPushState May 23 17:36:59 volumio volumio[1130]: info: ------------------------------ 91ms May 23 17:36:59 volumio volumio[1130]: info: ------------------------------ 54ms May 23 17:36:59 volumio volumio[1130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:37:08 volumio volumio[1130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 17:37:08 volumio volumio[1130]: Error: connect ETIMEDOUT 199.59.149.210:443 May 23 17:37:08 volumio volumio[1130]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 23 17:37:08 volumio volumio[1130]: errno: -110, May 23 17:37:08 volumio volumio[1130]: code: 'ETIMEDOUT', May 23 17:37:08 volumio volumio[1130]: syscall: 'connect', May 23 17:37:08 volumio volumio[1130]: address: '199.59.149.210', May 23 17:37:08 volumio volumio[1130]: port: 443 May 23 17:37:08 volumio volumio[1130]: } May 23 17:37:08 volumio volumio[1130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 17:37:08 volumio sudo[8099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 17:36 May 23 17:37:08 volumio sudo[8099]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"