-- Logs begin at Mon 2025-07-07 07:05:23 CST, end at Mon 2025-07-07 16:09:53 CST. -- Jul 07 16:08:10 volumio volumio[959]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 07 16:08:10 volumio volumio[959]: info: Preparing to save Alsa Options, stopping services first Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Jul 07 16:08:10 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::volumioPause Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::pause Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::servicePause Jul 07 16:08:10 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::servicePause Jul 07 16:08:10 volumio volumio[959]: info: ControllerMpd::pause Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand pause Jul 07 16:08:10 volumio volumio[959]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"}} Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:10 volumio volumio[959]: info: Setting default mixerSoftMaster for Softvolume device Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 07 16:08:10 volumio volumio[959]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 07 16:08:10 volumio volumio[959]: info: Disabling external Volume Control Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 07 16:08:10 volumio volumio[959]: info: Enable softmixer device for audio device undefined Jul 07 16:08:10 volumio volumio[959]: info: Jul 07 16:08:10 volumio volumio[959]: ---------------------------- MPD announces state update: player Jul 07 16:08:10 volumio volumio[959]: info: sendMpdCommand pause took 480 milliseconds Jul 07 16:08:10 volumio volumio[959]: info: ControllerMpd::getState Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Jul 07 16:08:10 volumio volumio[959]: info: Jul 07 16:08:10 volumio volumio[959]: ---------------------------- MPD announces state update: player Jul 07 16:08:10 volumio volumio[959]: info: ControllerMpd::getState Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Jul 07 16:08:10 volumio volumio[959]: info: Jul 07 16:08:10 volumio volumio[959]: ---------------------------- MPD announces state update: player Jul 07 16:08:10 volumio volumio[959]: info: ControllerMpd::getState Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Jul 07 16:08:10 volumio volumio[959]: info: sendMpdCommand status took 16 milliseconds Jul 07 16:08:10 volumio volumio[959]: info: sendMpdCommand status took 13 milliseconds Jul 07 16:08:10 volumio volumio[959]: info: sendMpdCommand status took 10 milliseconds Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::parseState Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::parseState Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::parseState Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 16:08:10 volumio volumio[959]: info: Software Volume ALSA configuration written Jul 07 16:08:10 volumio volumio[959]: info: Preparing to generate the ALSA configuration file Jul 07 16:08:10 volumio volumio[959]: info: sendMpdCommand playlistinfo took 43 milliseconds Jul 07 16:08:10 volumio volumio[959]: info: sendMpdCommand playlistinfo took 42 milliseconds Jul 07 16:08:10 volumio volumio[959]: info: sendMpdCommand playlistinfo took 42 milliseconds Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Jul 07 16:08:10 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Jul 07 16:08:10 volumio volumio[959]: info: ControllerMpd::pushState Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 07 16:08:10 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:10 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36079,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"齊秦","album":"齊秦精選","uri":"USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3","trackType":"mp3"} Jul 07 16:08:10 volumio volumio[959]: verbose: CURRENT POSITION 3 Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:10 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Jul 07 16:08:10 volumio volumio[959]: info: ControllerMpd::pushState Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 07 16:08:10 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:10 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36079,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"齊秦","album":"齊秦精選","uri":"USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3","trackType":"mp3"} Jul 07 16:08:10 volumio volumio[959]: verbose: CURRENT POSITION 3 Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:10 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Jul 07 16:08:10 volumio volumio[959]: info: ControllerMpd::pushState Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 07 16:08:10 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:10 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36079,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"齊秦","album":"齊秦精選","uri":"USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3","trackType":"mp3"} Jul 07 16:08:10 volumio volumio[959]: verbose: CURRENT POSITION 3 Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Jul 07 16:08:10 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:10 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:10 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:11 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Jul 07 16:08:11 volumio volumio[959]: info: ------------------------------ 139ms Jul 07 16:08:11 volumio volumio[959]: info: ------------------------------ 136ms Jul 07 16:08:11 volumio volumio[959]: info: ------------------------------ 136ms Jul 07 16:08:11 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:11 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:11 volumio volumio[959]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 07 16:08:11 volumio volumio[959]: info: Reading ALSA contributions from plugins. Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:11 volumio volumio[959]: info: Getting Alsa Cards List without I2S DAC Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 07 16:08:11 volumio volumio[959]: info: Asound.conf file unchanged, so no further update is needed Jul 07 16:08:11 volumio volumio[959]: info: Output device has changed, restarting MPD Jul 07 16:08:11 volumio volumio[959]: info: Output device has changed, restarting Shairport Sync Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:11 volumio sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 16:08:11 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:11 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:11 volumio sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 16:08:11 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:11 volumio systemd[1]: Stopping Music Player Daemon... Jul 07 16:08:11 volumio volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 16:08:11 volumio volumio[959]: info: MPD Permissions set Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:11 volumio systemd[1]: mpd.service: Succeeded. Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:11 volumio systemd[1]: Stopped Music Player Daemon. Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:11 volumio systemd[1]: Starting Music Player Daemon... Jul 07 16:08:11 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:11 volumio volumio[959]: error: Upnp client error: Error: This socket has been ended by the other party Jul 07 16:08:11 volumio volumio[959]: info: Starting Shairport Sync Jul 07 16:08:11 volumio sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 16:08:11 volumio sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 07 16:08:11 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 07 16:08:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:12 volumio sudo[1884]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:12 volumio volumio[959]: info: Shairport-Sync Started Jul 07 16:08:12 volumio sudo[1878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 07 16:08:12 volumio sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:12 volumio sudo[1878]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:14 volumio volumio[959]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 07 16:08:14 volumio volumio[959]: info: Preparing to save Alsa Options, stopping services first Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Jul 07 16:08:14 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::volumioPause Jul 07 16:08:14 volumio volumio[959]: info: CoreStateMachine::pause Jul 07 16:08:14 volumio volumio[959]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"}} Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:14 volumio volumio[959]: info: Setting default mixerSoftMaster for Softvolume device Jul 07 16:08:14 volumio mpd[1890]: Jul 07 16:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 07 16:08:14 volumio systemd[1]: Started Music Player Daemon. Jul 07 16:08:14 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 07 16:08:14 volumio volumio[959]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 07 16:08:14 volumio volumio[959]: info: Disabling external Volume Control Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 07 16:08:14 volumio volumio[959]: info: Enable softmixer device for audio device undefined Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 07 16:08:14 volumio volumio[959]: info: CoreStateMachine::getcurrentVolume Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:14 volumio volumio[959]: info: Software Volume ALSA configuration written Jul 07 16:08:14 volumio volumio[959]: info: Preparing to generate the ALSA configuration file Jul 07 16:08:14 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:14 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:14 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:15 volumio volumio[959]: info: Getting Alsa Cards List without I2S DAC Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 07 16:08:15 volumio volumio[959]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 07 16:08:15 volumio volumio[959]: info: Reading ALSA contributions from plugins. Jul 07 16:08:15 volumio volumio[959]: error: updateQueue error: null Jul 07 16:08:15 volumio volumio[959]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 07 16:08:15 volumio volumio[959]: info: Preparing to save Alsa Options, stopping services first Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Jul 07 16:08:15 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::volumioPause Jul 07 16:08:15 volumio volumio[959]: info: CoreStateMachine::pause Jul 07 16:08:15 volumio volumio[959]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"}} Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:15 volumio volumio[959]: info: Setting default mixerSoftMaster for Softvolume device Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 07 16:08:15 volumio volumio[959]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 07 16:08:15 volumio volumio[959]: info: Disabling external Volume Control Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 07 16:08:15 volumio volumio[959]: info: Enable softmixer device for audio device undefined Jul 07 16:08:15 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:15 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:15 volumio volumio[959]: info: CoreStateMachine::updateTrackBlock Jul 07 16:08:15 volumio volumio[959]: info: CorePlayQueue::getTrackBlock Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:15 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:15 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:15 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:15 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:15 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:15 volumio volumio[959]: info: Software Volume ALSA configuration written Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:16 volumio volumio[959]: info: Getting Alsa Cards List without I2S DAC Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 07 16:08:16 volumio volumio[959]: info: Asound.conf file written Jul 07 16:08:16 volumio sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 07 16:08:16 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:16 volumio sudo[1952]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:16 volumio volumio[959]: No state is present for card E30 Jul 07 16:08:16 volumio volumio[959]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" "" Jul 07 16:08:16 volumio volumio[959]: Hardware is initialized using a generic method Jul 07 16:08:16 volumio volumio[959]: No state is present for card E30 Jul 07 16:08:16 volumio volumio[959]: info: Output device has changed, restarting MPD Jul 07 16:08:16 volumio volumio[959]: info: Output device has changed, restarting Shairport Sync Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:16 volumio sudo[1957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 16:08:16 volumio sudo[1957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:16 volumio sudo[1957]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:16 volumio sudo[1959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 16:08:16 volumio sudo[1959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:16 volumio systemd[1]: Stopping Music Player Daemon... Jul 07 16:08:16 volumio volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 16:08:16 volumio volumio[959]: info: Preparing to generate the ALSA configuration file Jul 07 16:08:16 volumio volumio[959]: info: Output device has changed, restarting MPD Jul 07 16:08:16 volumio systemd[1]: mpd.service: Succeeded. Jul 07 16:08:16 volumio systemd[1]: Stopped Music Player Daemon. Jul 07 16:08:16 volumio volumio[959]: info: Output device has changed, restarting Shairport Sync Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:16 volumio systemd[1]: Starting Music Player Daemon... Jul 07 16:08:16 volumio sudo[1967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 16:08:16 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:16 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:16 volumio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 16:08:16 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 07 16:08:16 volumio systemd[1]: mpd.service: Succeeded. Jul 07 16:08:16 volumio systemd[1]: Stopped Music Player Daemon. Jul 07 16:08:16 volumio systemd[1]: Starting Music Player Daemon... Jul 07 16:08:16 volumio volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 16:08:16 volumio volumio[959]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 07 16:08:16 volumio volumio[959]: info: Reading ALSA contributions from plugins. Jul 07 16:08:16 volumio volumio[959]: error: Upnp client error: Error: This socket has been ended by the other party Jul 07 16:08:16 volumio volumio[959]: info: MPD Permissions set Jul 07 16:08:16 volumio volumio[959]: info: MPD Permissions set Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:16 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: Starting Shairport Sync Jul 07 16:08:17 volumio volumio[959]: info: Starting Shairport Sync Jul 07 16:08:17 volumio volumio[959]: info: Asound.conf file written Jul 07 16:08:17 volumio sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 16:08:17 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 16:08:17 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio sudo[1975]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 07 16:08:17 volumio sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio sudo[1990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 07 16:08:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 07 16:08:17 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio sudo[1975]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:17 volumio sudo[1990]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:17 volumio volumio[959]: No state is present for card E30 Jul 07 16:08:17 volumio volumio[959]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" "" Jul 07 16:08:17 volumio volumio[959]: Hardware is initialized using a generic method Jul 07 16:08:17 volumio volumio[959]: No state is present for card E30 Jul 07 16:08:17 volumio volumio[959]: info: Output device has changed, restarting MPD Jul 07 16:08:17 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 07 16:08:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:17 volumio volumio[959]: info: Output device has changed, restarting Shairport Sync Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:17 volumio sudo[1998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 16:08:17 volumio sudo[1998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio sudo[1998]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 07 16:08:17 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 07 16:08:17 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 07 16:08:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:17 volumio sudo[2001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 16:08:17 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:17 volumio sudo[1987]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:17 volumio volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 16:08:17 volumio volumio[959]: info: Output device has changed, restarting MPD Jul 07 16:08:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 07 16:08:17 volumio systemd[1]: mpd.service: Succeeded. Jul 07 16:08:17 volumio systemd[1]: Stopped Music Player Daemon. Jul 07 16:08:17 volumio systemd[1]: Starting Music Player Daemon... Jul 07 16:08:17 volumio sudo[2009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 16:08:17 volumio sudo[2009]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio volumio[959]: info: Output device has changed, restarting Shairport Sync Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:17 volumio sudo[2009]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:17 volumio sudo[2013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 16:08:17 volumio sudo[2013]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 07 16:08:17 volumio systemd[1]: mpd.service: Succeeded. Jul 07 16:08:17 volumio systemd[1]: Stopped Music Player Daemon. Jul 07 16:08:17 volumio volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 16:08:17 volumio volumio[959]: info: MPD Permissions set Jul 07 16:08:17 volumio volumio[959]: info: MPD Permissions set Jul 07 16:08:17 volumio volumio[959]: info: Shairport-Sync Started Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio systemd[1]: Starting Music Player Daemon... Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:17 volumio volumio[959]: info: Starting Shairport Sync Jul 07 16:08:17 volumio volumio[959]: info: Starting Shairport Sync Jul 07 16:08:17 volumio sudo[2030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 16:08:17 volumio sudo[2030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 16:08:17 volumio sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 07 16:08:18 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 07 16:08:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:18 volumio sudo[2020]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 07 16:08:18 volumio sudo[2020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:18 volumio sudo[2020]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:18 volumio sudo[2032]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:18 volumio sudo[2030]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:18 volumio volumio[959]: info: Shairport-Sync Started Jul 07 16:08:18 volumio volumio[959]: info: Shairport-Sync Started Jul 07 16:08:18 volumio volumio[959]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 07 16:08:18 volumio volumio[959]: info: CoreStateMachine::getcurrentVolume Jul 07 16:08:18 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:18 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:18 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:18 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:18 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:18 volumio volumio[959]: info: CoreStateMachine::updateTrackBlock Jul 07 16:08:18 volumio volumio[959]: info: CorePlayQueue::getTrackBlock Jul 07 16:08:18 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:18 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:18 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:18 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:19 volumio volumio[959]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 07 16:08:19 volumio volumio[959]: info: CoreStateMachine::getcurrentVolume Jul 07 16:08:19 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:19 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:19 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:19 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:19 volumio volumio[959]: info: CoreStateMachine::updateTrackBlock Jul 07 16:08:19 volumio volumio[959]: info: CorePlayQueue::getTrackBlock Jul 07 16:08:19 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:19 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:19 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:19 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:20 volumio mpd[2037]: Jul 07 16:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 07 16:08:20 volumio systemd[1]: Started Music Player Daemon. Jul 07 16:08:20 volumio sudo[2013]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:20 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:20 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:20 volumio sudo[1959]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:20 volumio volumio[959]: error: MPD error: The expression evaluated to a falsy value: Jul 07 16:08:20 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:20 volumio volumio[959]: error: The expression evaluated to a falsy value: Jul 07 16:08:20 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:20 volumio volumio[959]: error: MPD error: The expression evaluated to a falsy value: Jul 07 16:08:20 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:20 volumio volumio[959]: error: The expression evaluated to a falsy value: Jul 07 16:08:20 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:20 volumio volumio[959]: error: MPD error: The expression evaluated to a falsy value: Jul 07 16:08:20 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:20 volumio volumio[959]: error: The expression evaluated to a falsy value: Jul 07 16:08:20 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:20 volumio volumio[959]: error: updateQueue error: null Jul 07 16:08:25 volumio volumio[959]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 07 16:08:25 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 07 16:08:25 volumio volumio[959]: info: Preparing to save Alsa Options, stopping services first Jul 07 16:08:25 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Jul 07 16:08:25 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:25 volumio volumio[959]: info: CoreCommandRouter::volumioPause Jul 07 16:08:25 volumio volumio[959]: info: CoreStateMachine::pause Jul 07 16:08:25 volumio volumio[959]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"E30"},"i2s":false,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"}} Jul 07 16:08:25 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:25 volumio volumio[959]: info: Disabling I2S DAC: Jul 07 16:08:25 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC Jul 07 16:08:25 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:25 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:25 volumio volumio[959]: info: Setting default mixerSoftMaster for Softvolume device Jul 07 16:08:25 volumio volumio[959]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 07 16:08:25 volumio volumio[959]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 07 16:08:26 volumio volumio[959]: info: Disabling external Volume Control Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 07 16:08:26 volumio volumio[959]: info: Enable softmixer device for audio device undefined Jul 07 16:08:26 volumio sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 07 16:08:26 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:26 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:26 volumio volumio[959]: info: No Overlays Loaded Jul 07 16:08:26 volumio volumio[959]: info: Software Volume ALSA configuration written Jul 07 16:08:26 volumio volumio[959]: info: Preparing to generate the ALSA configuration file Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 07 16:08:26 volumio volumio[959]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 07 16:08:26 volumio volumio[959]: info: Reading ALSA contributions from plugins. Jul 07 16:08:26 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:26 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:26 volumio volumio[959]: info: Asound.conf file written Jul 07 16:08:26 volumio sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 07 16:08:26 volumio sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:26 volumio sudo[2104]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:26 volumio volumio[959]: No state is present for card E30 Jul 07 16:08:26 volumio volumio[959]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" "" Jul 07 16:08:26 volumio volumio[959]: Hardware is initialized using a generic method Jul 07 16:08:26 volumio volumio[959]: No state is present for card E30 Jul 07 16:08:26 volumio volumio[959]: info: Output device has changed, restarting MPD Jul 07 16:08:26 volumio volumio[959]: info: Output device has changed, restarting Shairport Sync Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:26 volumio sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 16:08:26 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:26 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:26 volumio sudo[2111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 16:08:26 volumio sudo[2111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:26 volumio systemd[1]: Stopping Music Player Daemon... Jul 07 16:08:26 volumio volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 16:08:26 volumio volumio[959]: info: Output device has changed, restarting MPD Jul 07 16:08:26 volumio systemd[1]: mpd.service: Succeeded. Jul 07 16:08:26 volumio systemd[1]: Stopped Music Player Daemon. Jul 07 16:08:26 volumio volumio[959]: info: Output device has changed, restarting Shairport Sync Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:26 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:26 volumio sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 16:08:26 volumio sudo[2118]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:26 volumio systemd[1]: Starting Music Player Daemon... Jul 07 16:08:26 volumio sudo[2118]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:26 volumio sudo[2121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 16:08:26 volumio sudo[2121]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:27 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 07 16:08:27 volumio systemd[1]: mpd.service: Succeeded. Jul 07 16:08:27 volumio systemd[1]: Stopped Music Player Daemon. Jul 07 16:08:27 volumio volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 16:08:27 volumio volumio[959]: info: VolumeController::SetAlsaVolume0 Jul 07 16:08:27 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:27 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:27 volumio systemd[1]: Starting Music Player Daemon... Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:27 volumio volumio[959]: info: MPD Permissions set Jul 07 16:08:27 volumio volumio[959]: info: MPD Permissions set Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:27 volumio volumio[959]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 07 16:08:27 volumio volumio[959]: info: Starting Shairport Sync Jul 07 16:08:27 volumio volumio[959]: info: Starting Shairport Sync Jul 07 16:08:27 volumio sudo[2138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 16:08:27 volumio sudo[2138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:27 volumio sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 16:08:27 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:27 volumio sudo[2127]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 07 16:08:27 volumio sudo[2127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:27 volumio sudo[2127]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 07 16:08:27 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 07 16:08:27 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:27 volumio sudo[2138]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:27 volumio volumio[959]: info: Shairport-Sync Started Jul 07 16:08:27 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:27 volumio volumio[959]: info: Shairport-Sync Started Jul 07 16:08:28 volumio volumio[959]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 07 16:08:28 volumio volumio[959]: info: Preparing to save Alsa Options, stopping services first Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Jul 07 16:08:28 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::volumioPause Jul 07 16:08:28 volumio volumio[959]: info: CoreStateMachine::pause Jul 07 16:08:28 volumio volumio[959]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"E30"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:28 volumio volumio[959]: info: Setting default mixerSoftMaster for Softvolume device Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 07 16:08:28 volumio volumio[959]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 07 16:08:28 volumio volumio[959]: info: Disabling external Volume Control Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 07 16:08:28 volumio volumio[959]: info: Enable softmixer device for audio device undefined Jul 07 16:08:28 volumio volumio[959]: info: Software Volume ALSA configuration written Jul 07 16:08:28 volumio volumio[959]: info: Preparing to generate the ALSA configuration file Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 07 16:08:28 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 07 16:08:28 volumio volumio[959]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 07 16:08:28 volumio volumio[959]: info: Reading ALSA contributions from plugins. Jul 07 16:08:28 volumio volumio[959]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 07 16:08:29 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:29 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:29 volumio volumio[959]: info: Asound.conf file unchanged, so no further update is needed Jul 07 16:08:29 volumio volumio[959]: info: Output device has changed, restarting MPD Jul 07 16:08:29 volumio volumio[959]: info: Output device has changed, restarting Shairport Sync Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:29 volumio sudo[2168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 16:08:29 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:29 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:29 volumio sudo[2170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 16:08:29 volumio sudo[2170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:29 volumio volumio[959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 16:08:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 07 16:08:29 volumio systemd[1]: mpd.service: Succeeded. Jul 07 16:08:29 volumio systemd[1]: Stopped Music Player Daemon. Jul 07 16:08:29 volumio volumio[959]: info: MPD Permissions set Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 16:08:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 16:08:29 volumio volumio[959]: info: Starting Shairport Sync Jul 07 16:08:29 volumio systemd[1]: Starting Music Player Daemon... Jul 07 16:08:29 volumio sudo[2182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 16:08:29 volumio sudo[2182]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 07 16:08:29 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 07 16:08:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 07 16:08:29 volumio sudo[2182]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:29 volumio volumio[959]: info: Shairport-Sync Started Jul 07 16:08:29 volumio volumio[959]: (node:959) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 07 16:08:29 volumio volumio[959]: (Use `node --trace-warnings ...` to show where the warning was created) Jul 07 16:08:29 volumio volumio[959]: (node:959) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 07 16:08:29 volumio volumio[959]: (node:959) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 07 16:08:29 volumio volumio[959]: (node:959) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 07 16:08:29 volumio volumio[959]: (node:959) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 07 16:08:29 volumio sudo[2176]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 07 16:08:29 volumio sudo[2176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 16:08:29 volumio sudo[2176]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:30 volumio volumio[959]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 07 16:08:30 volumio volumio[959]: info: CoreStateMachine::getcurrentVolume Jul 07 16:08:30 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:30 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:30 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:30 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:30 volumio volumio[959]: info: CoreStateMachine::updateTrackBlock Jul 07 16:08:30 volumio volumio[959]: info: CorePlayQueue::getTrackBlock Jul 07 16:08:30 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:30 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:30 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:30 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:32 volumio mpd[2190]: Jul 07 16:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 07 16:08:32 volumio systemd[1]: Started Music Player Daemon. Jul 07 16:08:32 volumio sudo[2121]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:32 volumio sudo[2111]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:32 volumio sudo[2170]: pam_unix(sudo:session): session closed for user root Jul 07 16:08:32 volumio volumio[959]: error: MPD error: The expression evaluated to a falsy value: Jul 07 16:08:32 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:32 volumio volumio[959]: error: The expression evaluated to a falsy value: Jul 07 16:08:32 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:32 volumio volumio[959]: error: MPD error: The expression evaluated to a falsy value: Jul 07 16:08:32 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:32 volumio volumio[959]: error: The expression evaluated to a falsy value: Jul 07 16:08:32 volumio volumio[959]: assert.ok(self.idling) Jul 07 16:08:32 volumio volumio[959]: error: updateQueue error: null Jul 07 16:08:32 volumio volumio[959]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 07 16:08:32 volumio volumio[959]: info: CoreStateMachine::getcurrentVolume Jul 07 16:08:32 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:32 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:32 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:32 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:32 volumio volumio[959]: info: CoreStateMachine::updateTrackBlock Jul 07 16:08:32 volumio volumio[959]: info: CorePlayQueue::getTrackBlock Jul 07 16:08:32 volumio volumio[959]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 16:08:32 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:32 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:32 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:35 volumio volumio[959]: info: CoreCommandRouter::volumioPlay Jul 07 16:08:35 volumio volumio[959]: info: CoreStateMachine::play index undefined Jul 07 16:08:35 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 16:08:35 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:35 volumio volumio[959]: info: CoreStateMachine::startPlaybackTimer Jul 07 16:08:35 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:35 volumio volumio[959]: info: ControllerMpd::resume Jul 07 16:08:35 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand play Jul 07 16:08:35 volumio volumio[959]: info: sendMpdCommand play took 2 milliseconds Jul 07 16:08:36 volumio volumio[959]: info: CoreCommandRouter::volumioPlay Jul 07 16:08:36 volumio volumio[959]: info: CoreStateMachine::play index undefined Jul 07 16:08:36 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 16:08:36 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:36 volumio volumio[959]: info: CoreStateMachine::startPlaybackTimer Jul 07 16:08:36 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:36 volumio volumio[959]: info: ControllerMpd::resume Jul 07 16:08:36 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand play Jul 07 16:08:36 volumio volumio[959]: info: sendMpdCommand play took 2 milliseconds Jul 07 16:08:38 volumio volumio[959]: info: CoreCommandRouter::volumioPlay Jul 07 16:08:38 volumio volumio[959]: info: CoreStateMachine::play index undefined Jul 07 16:08:38 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 16:08:38 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:38 volumio volumio[959]: info: CoreStateMachine::startPlaybackTimer Jul 07 16:08:38 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:38 volumio volumio[959]: info: ControllerMpd::resume Jul 07 16:08:38 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand play Jul 07 16:08:38 volumio volumio[959]: info: sendMpdCommand play took 2 milliseconds Jul 07 16:08:41 volumio volumio[959]: info: CoreCommandRouter::volumioPlay Jul 07 16:08:41 volumio volumio[959]: info: CoreStateMachine::play index undefined Jul 07 16:08:41 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 16:08:41 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:41 volumio volumio[959]: info: CoreStateMachine::startPlaybackTimer Jul 07 16:08:41 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:41 volumio volumio[959]: info: ControllerMpd::resume Jul 07 16:08:41 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand play Jul 07 16:08:41 volumio volumio[959]: info: sendMpdCommand play took 3 milliseconds Jul 07 16:08:45 volumio volumio[959]: info: CoreCommandRouter::volumioPlay Jul 07 16:08:45 volumio volumio[959]: info: CoreStateMachine::play index undefined Jul 07 16:08:45 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 16:08:45 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:45 volumio volumio[959]: info: CoreStateMachine::startPlaybackTimer Jul 07 16:08:45 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:45 volumio volumio[959]: info: ControllerMpd::resume Jul 07 16:08:45 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand play Jul 07 16:08:45 volumio volumio[959]: info: sendMpdCommand play took 2 milliseconds Jul 07 16:08:50 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 07 16:08:50 volumio volumio[959]: info: CURURI: music-library/USB/KINGSTON/齊秦/齊秦精選 Jul 07 16:08:50 volumio volumio[959]: info: Preload queue cleared Jul 07 16:08:52 volumio volumio[959]: info: Preload queue cleared Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::ClearQueue Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::stop Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::updateTrackBlock Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrackBlock Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::serviceStop Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::serviceStop Jul 07 16:08:52 volumio volumio[959]: info: ControllerMpd::stop Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand stop Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::clearPlayQueue Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::saveQueue Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::volumioPushQueue Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::addQueueItems Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::addQueueItems Jul 07 16:08:52 volumio volumio[959]: info: Preload queue cleared Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/01 大約在冬季.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/01 大約在冬季.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/02 冬雨.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/02 冬雨.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/03 獨行.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/03 獨行.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3 Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::volumioPushQueue Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::saveQueue Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::updateTrackBlock Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrackBlock Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::volumioPlay Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::play index 3 Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::addQueueItems Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::addQueueItems Jul 07 16:08:52 volumio volumio[959]: info: Preload queue cleared Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/05 埡口.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/05 埡口.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/06 狼II(巡行).mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/06 狼II(巡行).mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/07 狼.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/07 狼.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/08 原來的我.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/08 原來的我.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/09 花祭.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/09 花祭.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/10 九個太陽.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/10 九個太陽.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/11 感.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/11 感.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Adding Item to queue: music-library/USB/KINGSTON/齊秦/齊秦精選/12 讓我孤獨的時候還能夠想著你.mp3 Jul 07 16:08:52 volumio volumio[959]: info: Using cached record of: music-library/USB/KINGSTON/齊秦/齊秦精選/12 讓我孤獨的時候還能夠想著你.mp3 Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::stop Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::volumioPushQueue Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::saveQueue Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::play index undefined Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::updateTrackBlock Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrackBlock Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::startPlaybackTimer Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3 Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand stop Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand stop took 84 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand stop took 19 milliseconds Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand clear Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces system playlist update Jul 07 16:08:52 volumio volumio[959]: info: Ignoring MPD Status Update Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand clear took 5 milliseconds Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3" Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces system playlist update Jul 07 16:08:52 volumio volumio[959]: info: Ignoring MPD Status Update Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces system playlist update Jul 07 16:08:52 volumio volumio[959]: info: Ignoring MPD Status Update Jul 07 16:08:52 volumio volumio[959]: error: updateQueue error: null Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces system playlist update Jul 07 16:08:52 volumio volumio[959]: info: Ignoring MPD Status Update Jul 07 16:08:52 volumio volumio[959]: info: ------------------------------ 15ms Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand add "USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3" took 12 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: ------------------------------ 10ms Jul 07 16:08:52 volumio volumio[959]: info: ------------------------------ 7ms Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand play Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces system playlist update Jul 07 16:08:52 volumio volumio[959]: info: Ignoring MPD Status Update Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces system playlist update Jul 07 16:08:52 volumio volumio[959]: info: Ignoring MPD Status Update Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces state update: player Jul 07 16:08:52 volumio volumio[959]: info: ------------------------------ 18ms Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand play took 14 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: ------------------------------ 12ms Jul 07 16:08:52 volumio volumio[959]: info: ControllerMpd::getState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Jul 07 16:08:52 volumio volumio[959]: info: ------------------------------ 10ms Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces state update: player Jul 07 16:08:52 volumio volumio[959]: info: ControllerMpd::getState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces state update: player Jul 07 16:08:52 volumio volumio[959]: info: ControllerMpd::getState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand status took 108 milliseconds Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces state update: player Jul 07 16:08:52 volumio volumio[959]: info: ControllerMpd::getState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces state update: player Jul 07 16:08:52 volumio volumio[959]: info: ControllerMpd::getState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Jul 07 16:08:52 volumio volumio[959]: info: Jul 07 16:08:52 volumio volumio[959]: ---------------------------- MPD announces state update: player Jul 07 16:08:52 volumio volumio[959]: info: ControllerMpd::getState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand status took 121 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand status took 119 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand status took 11 milliseconds Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 16:08:52 volumio volumio[959]: info: ControllerMpd::pushState Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:52 volumio volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"齊秦","album":"齊秦精選","uri":"USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3","trackType":"mp3"} Jul 07 16:08:52 volumio volumio[959]: verbose: CURRENT POSITION 3 Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::syncState stateService play Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus stop Jul 07 16:08:52 volumio volumio[959]: info: ------------------------------ 143ms Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand status took 23 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand status took 20 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 07 16:08:52 volumio volumio[959]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseState Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Jul 07 16:08:52 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Jul 07 16:08:52 volumio volumio[959]: info: ControllerMpd::pushState Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:52 volumio volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":205,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"齊秦","album":"齊秦精選","uri":"USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3","trackType":"mp3"} Jul 07 16:08:52 volumio volumio[959]: verbose: CURRENT POSITION 3 Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::syncState stateService play Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus play Jul 07 16:08:52 volumio volumio[959]: info: Received an update from plugin. extracting info from payload Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:52 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:52 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:52 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:53 volumio volumio[959]: info: ControllerMpd::pushState Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"齊秦","album":"齊秦精選","uri":"USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3","trackType":"mp3"} Jul 07 16:08:53 volumio volumio[959]: verbose: CURRENT POSITION 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::syncState stateService play Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus play Jul 07 16:08:53 volumio volumio[959]: info: Received an update from plugin. extracting info from payload Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:53 volumio volumio[959]: info: ControllerMpd::pushState Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"齊秦","album":"齊秦精選","uri":"USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3","trackType":"mp3"} Jul 07 16:08:53 volumio volumio[959]: verbose: CURRENT POSITION 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::syncState stateService play Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus play Jul 07 16:08:53 volumio volumio[959]: info: Received an update from plugin. extracting info from payload Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:53 volumio volumio[959]: info: ------------------------------ 292ms Jul 07 16:08:53 volumio volumio[959]: info: ------------------------------ 291ms Jul 07 16:08:53 volumio volumio[959]: info: ------------------------------ 184ms Jul 07 16:08:53 volumio volumio[959]: info: sendMpdCommand playlistinfo took 154 milliseconds Jul 07 16:08:53 volumio volumio[959]: info: sendMpdCommand playlistinfo took 154 milliseconds Jul 07 16:08:53 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Jul 07 16:08:53 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Jul 07 16:08:53 volumio volumio[959]: info: ControllerMpd::pushState Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"齊秦","album":"齊秦精選","uri":"USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3","trackType":"mp3"} Jul 07 16:08:53 volumio volumio[959]: verbose: CURRENT POSITION 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::syncState stateService play Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus play Jul 07 16:08:53 volumio volumio[959]: info: Received an update from plugin. extracting info from payload Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:53 volumio volumio[959]: info: ControllerMpd::pushState Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::servicePushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"齊秦","album":"齊秦精選","uri":"USB/KINGSTON/齊秦/齊秦精選/04 外面的世界.mp3","trackType":"mp3"} Jul 07 16:08:53 volumio volumio[959]: verbose: CURRENT POSITION 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::syncState stateService play Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus play Jul 07 16:08:53 volumio volumio[959]: info: Received an update from plugin. extracting info from payload Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:53 volumio volumio[959]: info: CoreStateMachine::pushState Jul 07 16:08:53 volumio volumio[959]: info: CorePlayQueue::getTrack 3 Jul 07 16:08:53 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Jul 07 16:08:53 volumio volumio[959]: info: ------------------------------ 264ms Jul 07 16:08:53 volumio volumio[959]: info: ------------------------------ 263ms Jul 07 16:09:52 volumio volumio[959]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 16:09:52 volumio volumio[959]: Error: connect ETIMEDOUT 31.13.90.19:443 Jul 07 16:09:52 volumio volumio[959]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 07 16:09:52 volumio volumio[959]: errno: -110, Jul 07 16:09:52 volumio volumio[959]: code: 'ETIMEDOUT', Jul 07 16:09:52 volumio volumio[959]: syscall: 'connect', Jul 07 16:09:52 volumio volumio[959]: address: '31.13.90.19', Jul 07 16:09:52 volumio volumio[959]: port: 443 Jul 07 16:09:52 volumio volumio[959]: } Jul 07 16:09:52 volumio volumio[959]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 16:09:53 volumio sudo[2411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-07 16:08 Jul 07 16:09:53 volumio sudo[2411]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"